-- Logs begin at Thu 2024-08-29 13:34:25 IST, end at Thu 2024-08-29 14:05:48 IST. -- Aug 29 14:04:28 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:04:28 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:04:28 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:04:28 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:28 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:04:35 volumio sudo[7526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:04:35 volumio sudo[7526]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:04:35 volumio sudo[7526]: pam_unix(sudo:session): session closed for user root Aug 29 14:04:35 volumio sudo[7528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:04:35 volumio sudo[7528]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:04:35 volumio sudo[7528]: pam_unix(sudo:session): session closed for user root Aug 29 14:04:35 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 29 14:04:35 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:04:35 volumio sudo[7534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:04:35 volumio sudo[7534]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:04:35 volumio sudo[7534]: pam_unix(sudo:session): session closed for user root Aug 29 14:04:35 volumio sudo[7536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:04:35 volumio sudo[7536]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:04:35 volumio sudo[7536]: pam_unix(sudo:session): session closed for user root Aug 29 14:04:35 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:04:36 volumio volumio[1230]: info: Received Get System Info Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:04:36 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:36 volumio volumio[1230]: info: Listing playlists Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 14:04:36 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 14:04:37 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 14:04:37 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 14:04:37 volumio volumio[1230]: info: Received Get System Info Aug 29 14:04:37 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:04:37 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:04:37 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:04:37 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:37 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:04:38 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 14:04:38 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 14:04:38 volumio volumio[1230]: info: Get:26 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf libpython3-dev armhf 3.7.3-1 [20.1 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:27 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-multidict armhf 4.5.2-1 [52.9 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:28 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-yarl armhf 1.3.0-1 [44.0 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:29 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-async-timeout all 3.0.1-1 [6292 B] Aug 29 14:04:38 volumio volumio[1230]: info: Get:30 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-attr all 18.2.0-1 [37.3 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:31 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-aiohttp armhf 3.5.1-1+deb10u1 [241 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:32 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-asn1crypto all 0.24.0-1 [78.2 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:33 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-cffi-backend armhf 1.12.2-1 [70.1 kB] Aug 29 14:04:38 volumio volumio[1230]: info: Get:34 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-crypto armhf 2.6.1-9+b1 [251 kB] Aug 29 14:04:39 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 14:04:39 volumio volumio[1230]: info: Received Get System Info Aug 29 14:04:39 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:04:39 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:04:39 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:04:39 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:39 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:04:39 volumio volumio[1230]: info: Get:35 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-cryptography armhf 2.6.1-3+deb10u4 [184 kB] Aug 29 14:04:39 volumio volumio[1230]: info: Get:36 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-dbus armhf 1.2.8-3 [95.9 kB] Aug 29 14:04:39 volumio volumio[1230]: info: Get:37 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3.7-dev armhf 3.7.3-2+deb10u7 [527 kB] Aug 29 14:04:41 volumio volumio[1230]: info: Get:39 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-entrypoints all 0.3-1 [5508 B] Aug 29 14:04:41 volumio volumio[1230]: info: Get:40 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-gi armhf 3.30.4-1 [161 kB] Aug 29 14:04:42 volumio volumio[1230]: info: Get:41 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-secretstorage all 2.3.1-2 [14.2 kB] Aug 29 14:04:42 volumio volumio[1230]: info: Get:42 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-keyring all 17.1.1-1 [43.1 kB] Aug 29 14:04:43 volumio volumio[1230]: info: Get:43 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-keyrings.alt all 3.1.1-1 [18.2 kB] Aug 29 14:04:43 volumio volumio[1230]: info: Get:44 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-setuptools all 40.8.0-1 [306 kB] Aug 29 14:04:44 volumio volumio[1230]: info: Get:45 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-wheel all 0.32.3-2 [19.4 kB] Aug 29 14:04:44 volumio volumio[1230]: info: Get:46 http://mirror.ossplanet.net/raspbian/raspbian buster/main armhf python3-xdg all 0.25-5 [35.9 kB] Aug 29 14:04:45 volumio volumio[1230]: info: Fetched 72.4 MB in 1min 56s (627 kB/s) Aug 29 14:04:45 volumio volumio[1230]: info: Selecting previously unselected package bzip2. Aug 29 14:04:45 volumio volumio[1230]: [620B blob data] Aug 29 14:04:45 volumio volumio[1230]: info: Preparing to unpack .../00-bzip2_1.0.6-9.2~deb10u2_armhf.deb ... Aug 29 14:04:45 volumio volumio[1230]: info: Unpacking bzip2 (1.0.6-9.2~deb10u2) ... Aug 29 14:04:45 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 14:04:46 volumio volumio[1230]: info: Selecting previously unselected package libcc1-0:armhf. Aug 29 14:04:46 volumio volumio[1230]: info: Preparing to unpack .../01-libcc1-0_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:04:46 volumio volumio[1230]: info: Unpacking libcc1-0:armhf (8.3.0-6+rpi1) ... Aug 29 14:04:46 volumio volumio[1230]: info: Selecting previously unselected package libasan5:armhf. Aug 29 14:04:46 volumio volumio[1230]: info: Preparing to unpack .../02-libasan5_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:04:46 volumio volumio[1230]: info: Unpacking libasan5:armhf (8.3.0-6+rpi1) ... Aug 29 14:04:46 volumio volumio[1230]: info: Selecting previously unselected package libubsan1:armhf. Aug 29 14:04:46 volumio volumio[1230]: info: Preparing to unpack .../03-libubsan1_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:04:46 volumio volumio[1230]: info: Unpacking libubsan1:armhf (8.3.0-6+rpi1) ... Aug 29 14:04:46 volumio volumio[1230]: info: Selecting previously unselected package libgcc-8-dev:armhf. Aug 29 14:04:46 volumio volumio[1230]: info: Preparing to unpack .../04-libgcc-8-dev_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:04:46 volumio volumio[1230]: info: Unpacking libgcc-8-dev:armhf (8.3.0-6+rpi1) ... Aug 29 14:04:47 volumio volumio[1230]: info: Selecting previously unselected package gcc-8. Aug 29 14:04:47 volumio volumio[1230]: info: Preparing to unpack .../05-gcc-8_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:04:47 volumio volumio[1230]: info: Unpacking gcc-8 (8.3.0-6+rpi1) ... Aug 29 14:04:49 volumio volumio[1230]: info: Selecting previously unselected package gcc. Aug 29 14:04:49 volumio volumio[1230]: info: Preparing to unpack .../06-gcc_4%3a8.3.0-1+rpi2_armhf.deb ... Aug 29 14:04:49 volumio volumio[1230]: info: Unpacking gcc (4:8.3.0-1+rpi2) ... Aug 29 14:04:49 volumio volumio[1230]: info: Selecting previously unselected package libstdc++-8-dev:armhf. Aug 29 14:04:49 volumio volumio[1230]: info: Preparing to unpack .../07-libstdc++-8-dev_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:04:49 volumio volumio[1230]: info: Unpacking libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 14:04:54 volumio volumio[1230]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 14:04:54 volumio volumio[1230]: info: Received Get System Version Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:04:54 volumio volumio[1230]: info: Received Get System Info Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:04:54 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:54 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:04:58 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:04:58 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:04:58 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:04:58 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:04:58 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:00 volumio volumio[1230]: info: Selecting previously unselected package g++-8. Aug 29 14:05:00 volumio volumio[1230]: info: Preparing to unpack .../08-g++-8_8.3.0-6+rpi1_armhf.deb ... Aug 29 14:05:00 volumio volumio[1230]: info: Unpacking g++-8 (8.3.0-6+rpi1) ... Aug 29 14:05:02 volumio volumio[1230]: info: Selecting previously unselected package g++. Aug 29 14:05:02 volumio volumio[1230]: info: Preparing to unpack .../09-g++_4%3a8.3.0-1+rpi2_armhf.deb ... Aug 29 14:05:02 volumio volumio[1230]: info: Unpacking g++ (4:8.3.0-1+rpi2) ... Aug 29 14:05:02 volumio volumio[1230]: info: Selecting previously unselected package make. Aug 29 14:05:02 volumio volumio[1230]: info: Preparing to unpack .../10-make_4.2.1-1.2_armhf.deb ... Aug 29 14:05:02 volumio volumio[1230]: info: Unpacking make (4.2.1-1.2) ... Aug 29 14:05:02 volumio volumio[1230]: info: Selecting previously unselected package patch. Aug 29 14:05:02 volumio volumio[1230]: info: Preparing to unpack .../11-patch_2.7.6-3+deb10u1_armhf.deb ... Aug 29 14:05:02 volumio volumio[1230]: info: Unpacking patch (2.7.6-3+deb10u1) ... Aug 29 14:05:03 volumio volumio[1230]: info: Selecting previously unselected package dpkg-dev. Aug 29 14:05:03 volumio volumio[1230]: info: Preparing to unpack .../12-dpkg-dev_1.19.8_all.deb ... Aug 29 14:05:03 volumio volumio[1230]: info: Unpacking dpkg-dev (1.19.8) ... Aug 29 14:05:04 volumio volumio[1230]: info: Selecting previously unselected package build-essential. Aug 29 14:05:04 volumio volumio[1230]: info: Preparing to unpack .../13-build-essential_12.6_armhf.deb ... Aug 29 14:05:04 volumio volumio[1230]: info: Unpacking build-essential (12.6) ... Aug 29 14:05:04 volumio volumio[1230]: info: Selecting previously unselected package dh-python. Aug 29 14:05:04 volumio volumio[1230]: info: Preparing to unpack .../14-dh-python_3.20190308_all.deb ... Aug 29 14:05:04 volumio volumio[1230]: info: Unpacking dh-python (3.20190308) ... Aug 29 14:05:05 volumio volumio[1230]: info: Selecting previously unselected package libfakeroot:armhf. Aug 29 14:05:05 volumio volumio[1230]: info: Preparing to unpack .../15-libfakeroot_1.23-1_armhf.deb ... Aug 29 14:05:05 volumio volumio[1230]: info: Unpacking libfakeroot:armhf (1.23-1) ... Aug 29 14:05:05 volumio volumio[1230]: info: Selecting previously unselected package fakeroot. Aug 29 14:05:05 volumio volumio[1230]: info: Preparing to unpack .../16-fakeroot_1.23-1_armhf.deb ... Aug 29 14:05:05 volumio volumio[1230]: info: Unpacking fakeroot (1.23-1) ... Aug 29 14:05:05 volumio volumio[1230]: info: Selecting previously unselected package libalgorithm-diff-perl. Aug 29 14:05:05 volumio volumio[1230]: info: Preparing to unpack .../17-libalgorithm-diff-perl_1.19.03-2_all.deb ... Aug 29 14:05:05 volumio volumio[1230]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Aug 29 14:05:06 volumio volumio[1230]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Aug 29 14:05:06 volumio volumio[1230]: info: Preparing to unpack .../18-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Aug 29 14:05:06 volumio volumio[1230]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Aug 29 14:05:06 volumio volumio[1230]: info: Selecting previously unselected package libalgorithm-merge-perl. Aug 29 14:05:06 volumio volumio[1230]: info: Preparing to unpack .../19-libalgorithm-merge-perl_0.08-3_all.deb ... Aug 29 14:05:06 volumio volumio[1230]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Aug 29 14:05:06 volumio volumio[1230]: info: Selecting previously unselected package libexpat1-dev:armhf. Aug 29 14:05:06 volumio volumio[1230]: info: Preparing to unpack .../20-libexpat1-dev_2.2.6-2+deb10u7_armhf.deb ... Aug 29 14:05:06 volumio volumio[1230]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u7) ... Aug 29 14:05:06 volumio volumio[1230]: info: Selecting previously unselected package libpython3.7:armhf. Aug 29 14:05:06 volumio volumio[1230]: info: Preparing to unpack .../21-libpython3.7_3.7.3-2+deb10u7_armhf.deb ... Aug 29 14:05:06 volumio volumio[1230]: info: Unpacking libpython3.7:armhf (3.7.3-2+deb10u7) ... Aug 29 14:05:07 volumio volumio[1230]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:07 volumio volumio[1230]: (node:1230) UnhandledPromiseRejectionWarning: Error: The device code has expired, restarting auth flow. Aug 29 14:05:07 volumio volumio[1230]: at OAuth. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:23199:82) Aug 29 14:05:07 volumio volumio[1230]: at Generator.next () Aug 29 14:05:07 volumio volumio[1230]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Aug 29 14:05:07 volumio volumio[1230]: at runMicrotasks () Aug 29 14:05:07 volumio volumio[1230]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 29 14:05:07 volumio volumio[1230]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 29 14:05:07 volumio volumio[1230]: (node:1230) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Aug 29 14:05:07 volumio volumio[1230]: (node:1230) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Aug 29 14:05:07 volumio volumio[1230]: info: Selecting previously unselected package libpython3.7-dev:armhf. Aug 29 14:05:07 volumio volumio[1230]: info: Preparing to unpack .../22-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Aug 29 14:05:07 volumio volumio[1230]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Aug 29 14:05:08 volumio volumio[1230]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:14 volumio volumio[1230]: info: Selecting previously unselected package libpython3-dev:armhf. Aug 29 14:05:14 volumio volumio[1230]: info: Preparing to unpack .../23-libpython3-dev_3.7.3-1_armhf.deb ... Aug 29 14:05:14 volumio volumio[1230]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Aug 29 14:05:14 volumio volumio[1230]: info: Selecting previously unselected package python-pip-whl. Aug 29 14:05:14 volumio volumio[1230]: info: Preparing to unpack .../24-python-pip-whl_18.1-5+rpt1_all.deb ... Aug 29 14:05:14 volumio volumio[1230]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... Aug 29 14:05:15 volumio sudo[7861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:15 volumio sudo[7861]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:15 volumio sudo[7861]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:15 volumio volumio[1230]: info: Selecting previously unselected package python3-multidict. Aug 29 14:05:15 volumio sudo[7863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:15 volumio sudo[7863]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:15 volumio volumio[1230]: info: Preparing to unpack .../25-python3-multidict_4.5.2-1_armhf.deb ... Aug 29 14:05:15 volumio sudo[7863]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:15 volumio volumio[1230]: info: Unpacking python3-multidict (4.5.2-1) ... Aug 29 14:05:15 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 14:05:15 volumio sudo[7870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:15 volumio sudo[7870]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:15 volumio sudo[7870]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:15 volumio sudo[7873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:15 volumio sudo[7873]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:15 volumio sudo[7873]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:15 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 29 14:05:15 volumio volumio[1230]: info: Selecting previously unselected package python3-yarl. Aug 29 14:05:15 volumio volumio[1230]: info: Preparing to unpack .../26-python3-yarl_1.3.0-1_armhf.deb ... Aug 29 14:05:15 volumio volumio[1230]: info: Unpacking python3-yarl (1.3.0-1) ... Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:15 volumio volumio[1230]: info: Received Get System Info Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:15 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:15 volumio volumio[1230]: info: Listing playlists Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 14:05:15 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 14:05:16 volumio volumio[1230]: info: Selecting previously unselected package python3-async-timeout. Aug 29 14:05:16 volumio volumio[1230]: info: Preparing to unpack .../27-python3-async-timeout_3.0.1-1_all.deb ... Aug 29 14:05:16 volumio volumio[1230]: info: Unpacking python3-async-timeout (3.0.1-1) ... Aug 29 14:05:16 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 14:05:16 volumio volumio[1230]: info: Selecting previously unselected package python3-attr. Aug 29 14:05:16 volumio volumio[1230]: info: Preparing to unpack .../28-python3-attr_18.2.0-1_all.deb ... Aug 29 14:05:16 volumio volumio[1230]: info: Unpacking python3-attr (18.2.0-1) ... Aug 29 14:05:16 volumio volumio[1230]: info: Selecting previously unselected package python3-aiohttp. Aug 29 14:05:16 volumio volumio[1230]: info: Preparing to unpack .../29-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Aug 29 14:05:16 volumio volumio[1230]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Aug 29 14:05:17 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 14:05:17 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 14:05:17 volumio volumio[1230]: info: Received Get System Info Aug 29 14:05:17 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:17 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:17 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:05:17 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:17 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:17 volumio volumio[1230]: info: Selecting previously unselected package python3-asn1crypto. Aug 29 14:05:17 volumio volumio[1230]: info: Preparing to unpack .../30-python3-asn1crypto_0.24.0-1_all.deb ... Aug 29 14:05:17 volumio volumio[1230]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 14:05:18 volumio volumio[1230]: info: Selecting previously unselected package python3-cffi-backend. Aug 29 14:05:18 volumio volumio[1230]: info: Preparing to unpack .../31-python3-cffi-backend_1.12.2-1_armhf.deb ... Aug 29 14:05:18 volumio volumio[1230]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Aug 29 14:05:18 volumio volumio[1230]: info: Selecting previously unselected package python3-crypto. Aug 29 14:05:18 volumio volumio[1230]: info: Preparing to unpack .../32-python3-crypto_2.6.1-9+b1_armhf.deb ... Aug 29 14:05:18 volumio volumio[1230]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 14:05:18 volumio volumio[1230]: info: Received Get System Info Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:18 volumio volumio[1230]: info: Discovery: Getting this device information Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:20 volumio volumio[1230]: info: Selecting previously unselected package python3-cryptography. Aug 29 14:05:20 volumio volumio[1230]: info: Preparing to unpack .../33-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Aug 29 14:05:20 volumio volumio[1230]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Aug 29 14:05:22 volumio volumio[1230]: info: Selecting previously unselected package python3-dbus. Aug 29 14:05:22 volumio volumio[1230]: info: Preparing to unpack .../34-python3-dbus_1.2.8-3_armhf.deb ... Aug 29 14:05:22 volumio volumio[1230]: info: Unpacking python3-dbus (1.2.8-3) ... Aug 29 14:05:22 volumio volumio[1230]: info: Enabling plugin fusiondsp Aug 29 14:05:22 volumio volumio[1230]: info: Loading plugin "fusiondsp"... Aug 29 14:05:22 volumio volumio[1230]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:22 volumio volumio[1230]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:22 volumio volumio[1230]: info: Reading ALSA contributions from plugins. Aug 29 14:05:22 volumio volumio[1230]: info: Selecting previously unselected package python3.7-dev. Aug 29 14:05:22 volumio volumio[1230]: info: Preparing to unpack .../35-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Aug 29 14:05:22 volumio volumio[1230]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Aug 29 14:05:22 volumio volumio[1230]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:22 volumio volumio[1230]: info: Output device has changed, restarting MPD Aug 29 14:05:22 volumio sudo[7986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:22 volumio sudo[7986]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:22 volumio volumio[1230]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:22 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:22 volumio sudo[7986]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:23 volumio volumio[1230]: info: PLUGIN START: fusiondsp Aug 29 14:05:23 volumio volumio[1230]: info: Loading i18n strings for locale en Aug 29 14:05:23 volumio sudo[7989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:23 volumio sudo[7989]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:05:23 volumio volumio[1230]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:23 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:23 volumio volumio[1230]: info: Done. Aug 29 14:05:23 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:23 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:23 volumio volumio[1230]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:23 volumio volumio[1230]: info: Reading ALSA contributions from plugins. Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:23 volumio volumio[1230]: info: MPD Permissions set Aug 29 14:05:23 volumio volumio[1230]: info: Selecting previously unselected package python3-dev. Aug 29 14:05:23 volumio volumio[1230]: info: Preparing to unpack .../36-python3-dev_3.7.3-1_armhf.deb ... Aug 29 14:05:23 volumio volumio[1230]: info: Unpacking python3-dev (3.7.3-1) ... Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio sudo[7997]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:23 volumio sudo[7997]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio sudo[7997]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio volumio[1230]: info: Starting Shairport Sync Aug 29 14:05:23 volumio sudo[8012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:23 volumio sudo[8012]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio volumio[1230]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:23 volumio volumio[1230]: info: Output device has changed, restarting MPD Aug 29 14:05:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:23 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:23 volumio volumio[1230]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:23 volumio sudo[8015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:23 volumio sudo[8015]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:23 volumio sudo[8012]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio sudo[8015]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio sudo[8019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:23 volumio sudo[8019]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio volumio[1230]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 29 14:05:23 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:23 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:23 volumio volumio[1230]: info: MPD Permissions set Aug 29 14:05:23 volumio volumio[1230]: info: Shairport-Sync Started Aug 29 14:05:23 volumio volumio[1230]: info: Selecting previously unselected package python3-entrypoints. Aug 29 14:05:23 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:23 volumio volumio[1230]: info: Preparing to unpack .../37-python3-entrypoints_0.3-1_all.deb ... Aug 29 14:05:23 volumio volumio[1230]: info: Unpacking python3-entrypoints (0.3-1) ... Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:23 volumio volumio[1230]: info: Starting Shairport Sync Aug 29 14:05:23 volumio volumio[1230]: error: Upnp client error: Error: This socket has been ended by the other party Aug 29 14:05:23 volumio sudo[8029]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:23 volumio sudo[8029]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio sudo[8029]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio sudo[8035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:23 volumio sudo[8035]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:23 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:23 volumio sudo[8035]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio volumio[1230]: info: Shairport-Sync Started Aug 29 14:05:23 volumio volumio[1230]: info: Selecting previously unselected package python3-gi. Aug 29 14:05:23 volumio volumio[1230]: info: Preparing to unpack .../38-python3-gi_3.30.4-1_armhf.deb ... Aug 29 14:05:23 volumio volumio[1230]: info: Unpacking python3-gi (3.30.4-1) ... Aug 29 14:05:23 volumio mpd[8037]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:23 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:23 volumio sudo[8019]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio sudo[7989]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:23 volumio volumio[1230]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:05:23 volumio volumio[1230]: assert.ok(self.idling) Aug 29 14:05:23 volumio volumio[1230]: error: The expression evaluated to a falsy value: Aug 29 14:05:23 volumio volumio[1230]: assert.ok(self.idling) Aug 29 14:05:23 volumio volumio[1230]: error: updateQueue error: null Aug 29 14:05:24 volumio volumio[1230]: info: Selecting previously unselected package python3-secretstorage. Aug 29 14:05:24 volumio volumio[1230]: info: Preparing to unpack .../39-python3-secretstorage_2.3.1-2_all.deb ... Aug 29 14:05:24 volumio volumio[1230]: info: Unpacking python3-secretstorage (2.3.1-2) ... Aug 29 14:05:24 volumio volumio[1230]: info: Selecting previously unselected package python3-keyring. Aug 29 14:05:24 volumio volumio[1230]: info: Preparing to unpack .../40-python3-keyring_17.1.1-1_all.deb ... Aug 29 14:05:24 volumio volumio[1230]: info: Unpacking python3-keyring (17.1.1-1) ... Aug 29 14:05:25 volumio volumio[1230]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:05:25 volumio volumio[1230]: info: camilladsp service started and running in background, instance 1 Aug 29 14:05:25 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:25 volumio volumio[1230]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:25 volumio volumio[1230]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:05:25 volumio volumio[1230]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:25 volumio volumio[1230]: info: FusionDsp loaded Aug 29 14:05:25 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:25 volumio sudo[8087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:05:25 volumio sudo[8087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:25 volumio volumio[1230]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:05:25 volumio volumio[1230]: info: Adding Signal Path Element [object Object] Aug 29 14:05:25 volumio volumio[1230]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:05:25 volumio volumio[1230]: info: FusionDsp - ---- installed callbackRead Aug 29 14:05:25 volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:25 volumio volumio[1230]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:05:25 volumio volumio[1230]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:05:25 volumio volumio[1230]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:05:25 volumio volumio[1230]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 14:05:25 volumio volumio[1230]: errno: -2, Aug 29 14:05:25 volumio volumio[1230]: code: 'ENOENT', Aug 29 14:05:25 volumio volumio[1230]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:25 volumio volumio[1230]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:25 volumio volumio[1230]: spawnargs: [ Aug 29 14:05:25 volumio volumio[1230]: '-p', Aug 29 14:05:25 volumio volumio[1230]: 9876, Aug 29 14:05:25 volumio volumio[1230]: '-o', Aug 29 14:05:25 volumio volumio[1230]: '/tmp/camilladsp.log', Aug 29 14:05:25 volumio volumio[1230]: '-l', Aug 29 14:05:25 volumio volumio[1230]: 'warn', Aug 29 14:05:25 volumio volumio[1230]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:05:25 volumio volumio[1230]: ] Aug 29 14:05:25 volumio volumio[1230]: } Aug 29 14:05:25 volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:25 volumio sudo[8087]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:25 volumio sudo[8101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:04 Aug 29 14:05:25 volumio sudo[8101]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:25 volumio sudo[8101]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:26 volumio volumio-remote-updater[868]: [2024-08-29 14:05:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 14:05:26 volumio volumio-remote-updater[868]: [2024-08-29 14:05:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 14:05:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:05:26 volumio sudo[6962]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:26 volumio sudo[7205]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:05:26 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:26 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:26 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:05:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Aug 29 14:05:26 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:05:26 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:05:26 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:26 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:26 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:26 volumio volumio[8124]: info: ----- Volumio3 ---- Aug 29 14:05:26 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:26 volumio volumio[8124]: info: ----- System startup ---- Aug 29 14:05:26 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:26 volumio volumio[8124]: info: MYVOLUMIO Environment detected Aug 29 14:05:26 volumio volumio[8124]: info: Plugin folders cleanup Aug 29 14:05:26 volumio volumio[8124]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category audio_interface Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category miscellanea Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category music_service Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category plugins.json Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category system_controller Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category user_interface Aug 29 14:05:26 volumio volumio[8124]: info: Scanning into folder /data/plugins/ Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category audio_interface Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category music_service Aug 29 14:05:26 volumio volumio[8124]: info: Scanning category user_interface Aug 29 14:05:26 volumio volumio[8124]: info: Plugin folders cleanup completed Aug 29 14:05:26 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:26 volumio volumio[8124]: info: ----- Core plugins startup ---- Aug 29 14:05:26 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:05:26 volumio volumio[8124]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:05:26 volumio volumio[8124]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:05:26 volumio volumio[8124]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugins from folder /data/plugins/ Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugin "system"... Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugin "appearance"... Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugin "network"... Aug 29 14:05:26 volumio volumio[8124]: info: Refreshing Cached IP Addresses Aug 29 14:05:26 volumio sudo[8151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:26 volumio sudo[8151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:26 volumio sudo[8153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:26 volumio sudo[8153]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:26 volumio sudo[8151]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugin "services"... Aug 29 14:05:26 volumio volumio[8124]: info: Loading plugin "alsa_controller"... Aug 29 14:05:26 volumio sudo[8153]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:27 volumio sudo[8156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:05:27 volumio sudo[8156]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:27 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "wizard"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "networkfs"... Aug 29 14:05:27 volumio volumio[8124]: info: Starting Udev Watcher for removable devices Aug 29 14:05:27 volumio volumio[8124]: info: Mounting Device 7A6D-519E Aug 29 14:05:27 volumio sudo[8165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:27 volumio sudo[8165]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:27 volumio sudo[8165]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:27 volumio volumio[8124]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:27 volumio volumio[8124]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:27 volumio volumio[8124]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:27 volumio volumio[8124]: info: Ignoring mount for partition: boot Aug 29 14:05:27 volumio volumio[8124]: info: Ignoring mount for partition: volumio Aug 29 14:05:27 volumio volumio[8124]: info: Ignoring mount for partition: volumio_data Aug 29 14:05:27 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "upnp"... Aug 29 14:05:27 volumio volumio[8124]: info: [1724920527128] Starting Upmpd Daemon Aug 29 14:05:27 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "my_music"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "mpd"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "upnp_browser"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "alarm-clock"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "airplay_emulation"... Aug 29 14:05:27 volumio volumio[8124]: info: Starting Shairport Sync Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "last_100"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "webradio"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "i2s_dacs"... Aug 29 14:05:27 volumio volumio[8124]: info: I2S DAC not set, start Auto-detection Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "volumiodiscovery"... Aug 29 14:05:27 volumio volumio[8124]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:27 volumio volumio[8124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:27 volumio node[8124]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:27 volumio volumio[8124]: *** WARNING *** For more information see Aug 29 14:05:27 volumio volumio[8124]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:27 volumio volumio[8124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:27 volumio volumio[8124]: *** WARNING *** For more information see Aug 29 14:05:27 volumio node[8124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:27 volumio node[8124]: *** WARNING *** For more information see Aug 29 14:05:27 volumio node[8124]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:27 volumio node[8124]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:27 volumio node[8124]: *** WARNING *** For more information see Aug 29 14:05:27 volumio volumio[8124]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:05:27 volumio volumio[8124]: info: Discovery: Started advertising with name: Volumio Aug 29 14:05:27 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "youtube2"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "ytmusic"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "outputs"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "albumart"... Aug 29 14:05:27 volumio volumio[8124]: info: Plugin example_plugin is not enabled Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "inputs"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "updater_comm"... Aug 29 14:05:27 volumio volumio[8124]: info: Plugin mpdemulation is not enabled Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "rest_api"... Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "websocket"... Aug 29 14:05:27 volumio volumio[8124]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:05:27 volumio volumio[8124]: info: Loading plugin "fusiondsp"... Aug 29 14:05:27 volumio volumio[8124]: Forking 3 albumart workers Aug 29 14:05:27 volumio volumio[8124]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:05:28 volumio volumio[8124]: info: Loading plugin "touch_display"... Aug 29 14:05:28 volumio volumio[8124]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:05:28 volumio volumio[8124]: info: Loading i18n strings for locale en Aug 29 14:05:28 volumio volumio[8124]: Updating browse sources language Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: Starting albumart workers Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::initPlayerControls Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: Express server listening on port 3000 Aug 29 14:05:28 volumio volumio[8124]: [Metrics] WebUI: 1s 732.39ms Aug 29 14:05:28 volumio volumio[8124]: Starting albumart workers Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::resetVolumioState Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::getcurrentVolume Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: Starting albumart workers Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::pushState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::updateTrackBlock Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrackBlock Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:28 volumio volumio[8124]: info: Reloading queue from file Aug 29 14:05:28 volumio volumio[8124]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:05:28 volumio volumio[8124]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:05:28 volumio volumio[8124]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::pushState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::setRandom null Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::pushState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::pushState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:28 volumio volumio[8124]: info: Setting Device type: Raspberry PI Aug 29 14:05:28 volumio volumio[8124]: info: Completed loading Core Plugins Aug 29 14:05:28 volumio volumio[8124]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:28 volumio volumio[8124]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:28 volumio volumio[8124]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:28 volumio volumio[8124]: info: Reading ALSA contributions from plugins. Aug 29 14:05:28 volumio volumio[8124]: info: Asound.conf file written Aug 29 14:05:28 volumio sudo[8236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 29 14:05:28 volumio sudo[8236]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio sudo[8236]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio volumio[8124]: info: Output device has changed, restarting MPD Aug 29 14:05:28 volumio sudo[8241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:28 volumio volumio[8124]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:28 volumio sudo[8241]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio sudo[8241]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio sudo[8243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:28 volumio sudo[8243]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:28 volumio volumio[8124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:28 volumio volumio[8124]: info: ___________ START PLUGINS ___________ Aug 29 14:05:28 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:28 volumio volumio[8124]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:05:28 volumio volumio[8124]: info: Creating MPD Configuration file Aug 29 14:05:28 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:28 volumio sudo[8259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:28 volumio sudo[8259]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:28 volumio volumio[8124]: info: [1724920528465] CoreMusicLibrary::Adding element Media Servers Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio sudo[8259]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio sudo[8262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:28 volumio sudo[8262]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:28 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:05:28 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:28 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:28 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:28 volumio volumio[8124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:28 volumio volumio[8124]: info: [1724920528509] CoreMusicLibrary::Adding element Last_100 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:28 volumio volumio[8124]: info: [1724920528510] CoreMusicLibrary::Adding element Webradio Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:28 volumio sudo[8267]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:28 volumio sudo[8267]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: Initializing BBC Radios Aug 29 14:05:28 volumio sudo[8267]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:28 volumio volumio[8124]: info: [1724920528543] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: Cannot find translation for source YouTube2 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:28 volumio volumio[8124]: info: [1724920528545] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: Cannot find translation for source YouTube2 Aug 29 14:05:28 volumio volumio[8124]: Cannot find translation for source YouTube Music Aug 29 14:05:28 volumio volumio[8124]: info: Loading i18n strings for locale en Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:05:28 volumio volumio[8124]: info: Loading i18n strings for locale en Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:28 volumio volumio[8124]: info: Volumio Calling Home Aug 29 14:05:28 volumio volumio[8124]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:28 volumio sudo[8287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:05:28 volumio sudo[8287]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio sudo[8289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:05:28 volumio sudo[8289]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio sudo[8287]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio sudo[8293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:05:28 volumio sudo[8293]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio systemd[1]: Reloading. Aug 29 14:05:28 volumio volumio[8124]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:05:28 volumio volumio[8124]: info: Discovery: Found device Volumio Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:28 volumio volumio[8124]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:28 volumio volumio[8124]: info: Reading ALSA contributions from plugins. Aug 29 14:05:28 volumio volumio[8124]: info: touch_display: No backlight interface detected. Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:28 volumio volumio[8124]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:05:28 volumio volumio[8124]: info: MPD Permissions set Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:28 volumio volumio[8124]: info: Discovery: Getting this device information Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:05:28 volumio sudo[8317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:05:28 volumio sudo[8317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: Starting Shairport Sync Aug 29 14:05:28 volumio sudo[8317]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio volumio[8124]: info: Starting Shairport Sync Aug 29 14:05:28 volumio sudo[8320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:05:28 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio volumio[8124]: info: Starting Shairport Sync Aug 29 14:05:28 volumio sudo[8325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:28 volumio sudo[8324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:28 volumio sudo[8325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio sudo[8324]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:28 volumio mpd[8273]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:28 volumio sudo[8329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:28 volumio sudo[8329]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:05:28 volumio volumio[8124]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:28 volumio volumio[8124]: info: Output device has changed, restarting MPD Aug 29 14:05:28 volumio sudo[8334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:28 volumio sudo[8334]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio sudo[8334]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio sudo[8341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:28 volumio sudo[8341]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:28 volumio volumio[8124]: info: MPD Permissions set Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:28 volumio volumio[8124]: info: Starting Shairport Sync Aug 29 14:05:28 volumio sudo[8387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:28 volumio sudo[8387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:28 volumio volumio[8124]: info: Received Get System Info Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:28 volumio volumio[8124]: info: Discovery: Getting this device information Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: Listing playlists Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:28 volumio volumio[8124]: info: Received Get System Info Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:28 volumio volumio[8124]: info: Discovery: Getting this device information Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: Listing playlists Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetQueue Aug 29 14:05:28 volumio volumio[8124]: info: CoreStateMachine::getQueue Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getQueue Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 192.168.31.7:3000 from 192.168.31.43 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 29 14:05:28 volumio volumio[8124]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 29 14:05:28 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:28 volumio sudo[8289]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio sudo[8243]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio sudo[8262]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:28 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 14:05:28 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 14:05:28 volumio systemd[1]: Reloading. Aug 29 14:05:28 volumio volumio[8124]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:05:29 volumio volumio[8124]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:05:29 volumio volumio[8124]: assert.ok(self.idling) Aug 29 14:05:29 volumio volumio[8124]: error: The expression evaluated to a falsy value: Aug 29 14:05:29 volumio volumio[8124]: assert.ok(self.idling) Aug 29 14:05:29 volumio volumio[8124]: error: updateQueue error: null Aug 29 14:05:29 volumio volumio[8124]: info: MPD running with PID8273 Aug 29 14:05:29 volumio volumio[8124]: ,establishing connection Aug 29 14:05:29 volumio volumio[8124]: error: updateQueue error: null Aug 29 14:05:29 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:29 volumio sudo[8293]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:05:29 volumio sudo[8419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:05:29 volumio sudo[8419]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:29 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:29 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:29 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:29 volumio sudo[8387]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio sudo[8329]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio sudo[8325]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio sudo[8324]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio volumio[8124]: info: Shairport-Sync Started Aug 29 14:05:29 volumio sudo[8419]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio volumio[8124]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:05:29 volumio volumio[8124]: info: Shairport-Sync Started Aug 29 14:05:29 volumio volumio[8124]: info: Shairport-Sync Started Aug 29 14:05:29 volumio volumio[8124]: info: Shairport-Sync Started Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio sudo[8422]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:29 volumio sudo[8422]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:29 volumio sudo[8422]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:29 volumio volumio[8124]: info: Received Get System Info Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:29 volumio volumio[8124]: info: Discovery: Getting this device information Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: Listing playlists Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: Volumio Kiosk started. Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: Completed starting Core Plugins Aug 29 14:05:29 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:29 volumio volumio[8124]: info: ----- MyVolumio plugins startup ---- Aug 29 14:05:29 volumio volumio[8124]: info: ------------------------------------------- Aug 29 14:05:29 volumio volumio[8124]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: X display number found: 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: X display number found: 0 Aug 29 14:05:29 volumio mpd[8426]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:29 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:29 volumio sudo[8341]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:29 volumio volumio[8124]: error: updateQueue error: null Aug 29 14:05:29 volumio volumio[8124]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:05:29 volumio volumio[8124]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:29 volumio volumio[8124]: info: Received Get System Info Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:29 volumio volumio[8124]: info: Discovery: Getting this device information Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:29 volumio volumio[8124]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:29 volumio volumio[8124]: info: Listing playlists Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:29 volumio volumio[8124]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:30 volumio volumio[8124]: info: Volumio called home Aug 29 14:05:30 volumio volumio[8124]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:30 volumio volumio[8124]: 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 Aug 29 14:05:30 volumio volumio[8124]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:30 volumio volumio[8124]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:05:30 volumio volumio[8124]: info: camilladsp service started and running in background, instance 1 Aug 29 14:05:30 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:30 volumio volumio[8124]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:30 volumio volumio[8124]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:05:30 volumio volumio[8124]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:30 volumio volumio[8124]: info: FusionDsp loaded Aug 29 14:05:30 volumio volumio[8124]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:30 volumio sudo[8451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:05:30 volumio sudo[8451]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:30 volumio volumio[8124]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:05:30 volumio volumio[8124]: info: Adding Signal Path Element [object Object] Aug 29 14:05:30 volumio volumio[8124]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:05:30 volumio volumio[8124]: info: FusionDsp - ---- installed callbackRead Aug 29 14:05:30 volumio volumio[8124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:30 volumio sudo[8451]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:30 volumio volumio[8124]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:05:30 volumio volumio[8124]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:05:30 volumio volumio[8124]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:05:30 volumio volumio[8124]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 14:05:30 volumio volumio[8124]: errno: -2, Aug 29 14:05:30 volumio volumio[8124]: code: 'ENOENT', Aug 29 14:05:30 volumio volumio[8124]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:30 volumio volumio[8124]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:30 volumio volumio[8124]: spawnargs: [ Aug 29 14:05:30 volumio volumio[8124]: '-p', Aug 29 14:05:30 volumio volumio[8124]: 9876, Aug 29 14:05:30 volumio volumio[8124]: '-o', Aug 29 14:05:30 volumio volumio[8124]: '/tmp/camilladsp.log', Aug 29 14:05:30 volumio volumio[8124]: '-l', Aug 29 14:05:30 volumio volumio[8124]: 'warn', Aug 29 14:05:30 volumio volumio[8124]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:05:30 volumio volumio[8124]: ] Aug 29 14:05:30 volumio volumio[8124]: } Aug 29 14:05:30 volumio volumio[8124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:30 volumio sudo[8462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:04 Aug 29 14:05:30 volumio sudo[8462]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:30 volumio sudo[8156]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:30 volumio sudo[8462]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:30 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:05:30 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:05:30 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:30 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:31 volumio volumio-remote-updater[868]: [2024-08-29 14:05:31] [info] asio async_connect error: system:111 (Connection refused) Aug 29 14:05:31 volumio volumio-remote-updater[868]: [2024-08-29 14:05:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 29 14:05:31 volumio volumio-remote-updater[868]: [2024-08-29 14:05:31] [error] handle_connect error: Underlying Transport Error Aug 29 14:05:31 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:05:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 29 14:05:31 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:05:31 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:05:31 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:31 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:31 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:31 volumio volumio[8473]: info: ----- Volumio3 ---- Aug 29 14:05:31 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:31 volumio volumio[8473]: info: ----- System startup ---- Aug 29 14:05:31 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:31 volumio volumio[8473]: info: MYVOLUMIO Environment detected Aug 29 14:05:31 volumio volumio[8473]: info: Plugin folders cleanup Aug 29 14:05:31 volumio volumio[8473]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category audio_interface Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category miscellanea Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category music_service Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category plugins.json Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category system_controller Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category user_interface Aug 29 14:05:31 volumio volumio[8473]: info: Scanning into folder /data/plugins/ Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category audio_interface Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category music_service Aug 29 14:05:31 volumio volumio[8473]: info: Scanning category user_interface Aug 29 14:05:31 volumio volumio[8473]: info: Plugin folders cleanup completed Aug 29 14:05:31 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:31 volumio volumio[8473]: info: ----- Core plugins startup ---- Aug 29 14:05:31 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:31 volumio volumio[8473]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:05:31 volumio volumio[8473]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:05:31 volumio volumio[8473]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:05:31 volumio volumio[8473]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:05:31 volumio volumio[8473]: info: Loading plugins from folder /data/plugins/ Aug 29 14:05:31 volumio volumio[8473]: info: Loading plugin "system"... Aug 29 14:05:31 volumio volumio[8473]: info: Loading plugin "appearance"... Aug 29 14:05:31 volumio volumio[8473]: info: Loading plugin "network"... Aug 29 14:05:31 volumio volumio[8473]: info: Refreshing Cached IP Addresses Aug 29 14:05:32 volumio sudo[8500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:32 volumio sudo[8500]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:32 volumio sudo[8500]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "services"... Aug 29 14:05:32 volumio sudo[8502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:32 volumio sudo[8502]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "alsa_controller"... Aug 29 14:05:32 volumio sudo[8502]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:32 volumio sudo[8505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:05:32 volumio sudo[8505]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:32 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "wizard"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "networkfs"... Aug 29 14:05:32 volumio volumio[8473]: info: Starting Udev Watcher for removable devices Aug 29 14:05:32 volumio volumio[8473]: info: Mounting Device 7A6D-519E Aug 29 14:05:32 volumio sudo[8514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:32 volumio sudo[8514]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:32 volumio sudo[8514]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:32 volumio volumio[8473]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:32 volumio volumio[8473]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:32 volumio volumio[8473]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:32 volumio volumio[8473]: info: Ignoring mount for partition: boot Aug 29 14:05:32 volumio volumio[8473]: info: Ignoring mount for partition: volumio Aug 29 14:05:32 volumio volumio[8473]: info: Ignoring mount for partition: volumio_data Aug 29 14:05:32 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "upnp"... Aug 29 14:05:32 volumio volumio[8473]: info: [1724920532077] Starting Upmpd Daemon Aug 29 14:05:32 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "my_music"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "mpd"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "upnp_browser"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "alarm-clock"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "airplay_emulation"... Aug 29 14:05:32 volumio volumio[8473]: info: Starting Shairport Sync Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "last_100"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "webradio"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "i2s_dacs"... Aug 29 14:05:32 volumio volumio[8473]: info: I2S DAC not set, start Auto-detection Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "volumiodiscovery"... Aug 29 14:05:32 volumio volumio[8473]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:32 volumio node[8473]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:32 volumio node[8473]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:32 volumio volumio[8473]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:32 volumio volumio[8473]: *** WARNING *** For more information see Aug 29 14:05:32 volumio volumio[8473]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:32 volumio volumio[8473]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:32 volumio volumio[8473]: *** WARNING *** For more information see Aug 29 14:05:32 volumio node[8473]: *** WARNING *** For more information see Aug 29 14:05:32 volumio node[8473]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:32 volumio node[8473]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:32 volumio node[8473]: *** WARNING *** For more information see Aug 29 14:05:32 volumio volumio[8473]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:05:32 volumio volumio[8473]: info: Discovery: Started advertising with name: Volumio Aug 29 14:05:32 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "youtube2"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "ytmusic"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "outputs"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "albumart"... Aug 29 14:05:32 volumio volumio[8473]: info: Plugin example_plugin is not enabled Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "inputs"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "updater_comm"... Aug 29 14:05:32 volumio volumio[8473]: info: Plugin mpdemulation is not enabled Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "rest_api"... Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "websocket"... Aug 29 14:05:32 volumio volumio[8473]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "fusiondsp"... Aug 29 14:05:32 volumio volumio[8473]: Forking 3 albumart workers Aug 29 14:05:32 volumio volumio[8473]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:05:32 volumio volumio[8473]: info: Loading plugin "touch_display"... Aug 29 14:05:32 volumio volumio[8473]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:05:33 volumio volumio[8473]: info: Loading i18n strings for locale en Aug 29 14:05:33 volumio volumio[8473]: Updating browse sources language Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::initPlayerControls Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: Express server listening on port 3000 Aug 29 14:05:33 volumio volumio[8473]: [Metrics] WebUI: 1s 577.22ms Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::resetVolumioState Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::getcurrentVolume Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: Starting albumart workers Aug 29 14:05:33 volumio volumio[8473]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:05:33 volumio volumio[8473]: Starting albumart workers Aug 29 14:05:33 volumio volumio[8473]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::pushState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::updateTrackBlock Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrackBlock Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:33 volumio volumio[8473]: info: Reloading queue from file Aug 29 14:05:33 volumio volumio[8473]: Starting albumart workers Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::pushState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::setRandom null Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::pushState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:33 volumio volumio[8473]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:05:33 volumio volumio[8473]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:05:33 volumio volumio[8473]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:05:33 volumio volumio[8473]: info: CoreStateMachine::pushState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:33 volumio volumio[8473]: info: Setting Device type: Raspberry PI Aug 29 14:05:33 volumio volumio[8473]: info: Completed loading Core Plugins Aug 29 14:05:33 volumio volumio[8473]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:33 volumio volumio[8473]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:33 volumio volumio[8473]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:33 volumio volumio[8473]: info: Reading ALSA contributions from plugins. Aug 29 14:05:33 volumio volumio[8473]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:33 volumio volumio[8473]: info: Output device has changed, restarting MPD Aug 29 14:05:33 volumio volumio[8473]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio sudo[8579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:33 volumio sudo[8580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:33 volumio sudo[8579]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8580]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8579]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:33 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:33 volumio volumio[8473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:33 volumio volumio[8473]: info: ___________ START PLUGINS ___________ Aug 29 14:05:33 volumio volumio[8473]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:05:33 volumio volumio[8473]: info: Creating MPD Configuration file Aug 29 14:05:33 volumio sudo[8596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:33 volumio sudo[8596]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:33 volumio volumio[8473]: info: [1724920533317] CoreMusicLibrary::Adding element Media Servers Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:33 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:33 volumio sudo[8599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:33 volumio sudo[8599]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8596]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:05:33 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:33 volumio volumio[8473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio sudo[8601]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:33 volumio sudo[8601]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8601]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio volumio[8473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:33 volumio volumio[8473]: info: [1724920533374] CoreMusicLibrary::Adding element Last_100 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:33 volumio volumio[8473]: info: [1724920533375] CoreMusicLibrary::Adding element Webradio Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:33 volumio volumio[8473]: info: Initializing BBC Radios Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:33 volumio volumio[8473]: info: [1724920533405] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:33 volumio volumio[8473]: Cannot find translation for source YouTube2 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:33 volumio volumio[8473]: info: [1724920533407] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:33 volumio volumio[8473]: Cannot find translation for source YouTube2 Aug 29 14:05:33 volumio volumio[8473]: Cannot find translation for source YouTube Music Aug 29 14:05:33 volumio volumio[8473]: info: Loading i18n strings for locale en Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:05:33 volumio volumio[8473]: info: Loading i18n strings for locale en Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:33 volumio volumio[8473]: info: Volumio Calling Home Aug 29 14:05:33 volumio volumio[8473]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:33 volumio sudo[8632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:05:33 volumio sudo[8632]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:05:33 volumio sudo[8637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:05:33 volumio sudo[8634]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8632]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio systemd[1]: Reloading. Aug 29 14:05:33 volumio volumio[8473]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:05:33 volumio volumio[8473]: info: Discovery: Found device Volumio Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:33 volumio volumio[8473]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:33 volumio volumio[8473]: info: Reading ALSA contributions from plugins. Aug 29 14:05:33 volumio volumio[8473]: info: touch_display: No backlight interface detected. Aug 29 14:05:33 volumio volumio[8473]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:05:33 volumio volumio[8473]: info: MPD Permissions set Aug 29 14:05:33 volumio volumio[8473]: info: MPD Permissions set Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:33 volumio volumio[8473]: info: Discovery: Getting this device information Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:33 volumio volumio[8473]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:05:33 volumio sudo[8663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:05:33 volumio sudo[8663]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8663]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio volumio[8473]: info: Starting Shairport Sync Aug 29 14:05:33 volumio sudo[8666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:05:33 volumio sudo[8666]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:33 volumio sudo[8666]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio volumio[8473]: info: Starting Shairport Sync Aug 29 14:05:33 volumio sudo[8669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:33 volumio sudo[8669]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: info: Starting Shairport Sync Aug 29 14:05:33 volumio volumio[8473]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:05:33 volumio volumio[8473]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:05:33 volumio sudo[8674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:33 volumio sudo[8674]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:33 volumio volumio[8473]: info: Output device has changed, restarting MPD Aug 29 14:05:33 volumio mpd[8617]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:33 volumio sudo[8673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:33 volumio sudo[8673]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio sudo[8680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:33 volumio sudo[8680]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio sudo[8680]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio sudo[8683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:33 volumio sudo[8683]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: MPD Permissions set Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:33 volumio volumio[8473]: info: Starting Shairport Sync Aug 29 14:05:33 volumio sudo[8693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:33 volumio sudo[8693]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: info: Volumio called home Aug 29 14:05:33 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:33 volumio sudo[8580]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio sudo[8599]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio volumio[8473]: verbose: New Socket.io Connection to 192.168.31.7:3000 from 192.168.31.43 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Aug 29 14:05:33 volumio sudo[8637]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:33 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 14:05:33 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 14:05:33 volumio volumio[8473]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:05:33 volumio systemd[1]: Reloading. Aug 29 14:05:33 volumio sudo[8698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:05:33 volumio sudo[8698]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:33 volumio volumio[8473]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:05:33 volumio volumio[8473]: assert.ok(self.idling) Aug 29 14:05:33 volumio volumio[8473]: error: The expression evaluated to a falsy value: Aug 29 14:05:33 volumio volumio[8473]: assert.ok(self.idling) Aug 29 14:05:33 volumio volumio[8473]: info: MPD running with PID8617 Aug 29 14:05:33 volumio volumio[8473]: ,establishing connection Aug 29 14:05:33 volumio volumio[8473]: error: updateQueue error: null Aug 29 14:05:33 volumio volumio[8473]: error: updateQueue error: null Aug 29 14:05:33 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:33 volumio volumio[8473]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 14:05:34 volumio sudo[8634]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:05:34 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:34 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:34 volumio sudo[8698]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:34 volumio sudo[8673]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: Volumio Kiosk started. Aug 29 14:05:34 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:34 volumio sudo[8669]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:34 volumio sudo[8674]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio sudo[8693]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:34 volumio volumio[8473]: info: Completed starting Core Plugins Aug 29 14:05:34 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:34 volumio volumio[8473]: info: ----- MyVolumio plugins startup ---- Aug 29 14:05:34 volumio volumio[8473]: info: ------------------------------------------- Aug 29 14:05:34 volumio volumio[8473]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: Shairport-Sync Started Aug 29 14:05:34 volumio volumio[8473]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:05:34 volumio volumio[8473]: info: Shairport-Sync Started Aug 29 14:05:34 volumio volumio[8473]: info: Shairport-Sync Started Aug 29 14:05:34 volumio volumio[8473]: info: Shairport-Sync Started Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: X display number found: 0 Aug 29 14:05:34 volumio sudo[8716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:34 volumio sudo[8716]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:34 volumio sudo[8716]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 14:05:34 volumio volumio[8473]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 14:05:34 volumio volumio[8473]: info: Received Get System Version Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:34 volumio volumio[8473]: info: Received Get System Info Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:34 volumio volumio[8473]: info: Discovery: Getting this device information Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:34 volumio volumio[8473]: info: Received Get System Info Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:34 volumio volumio[8473]: info: Discovery: Getting this device information Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: Listing playlists Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: X display number found: 0 Aug 29 14:05:34 volumio volumio[8473]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:05:34 volumio mpd[8720]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:34 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:34 volumio sudo[8683]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:34 volumio volumio[8473]: error: updateQueue error: null Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:34 volumio volumio[8473]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:34 volumio volumio[8473]: info: Received Get System Info Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:34 volumio volumio[8473]: info: Discovery: Getting this device information Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:34 volumio volumio[8473]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:34 volumio volumio[8473]: info: Listing playlists Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:34 volumio volumio[8473]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:35 volumio volumio[8473]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:35 volumio volumio[8473]: 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 Aug 29 14:05:35 volumio volumio[8473]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:35 volumio volumio[8473]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:05:35 volumio volumio[8473]: info: camilladsp service started and running in background, instance 1 Aug 29 14:05:35 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:35 volumio volumio[8473]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:35 volumio volumio[8473]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:05:35 volumio volumio[8473]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:35 volumio volumio[8473]: info: FusionDsp loaded Aug 29 14:05:35 volumio volumio[8473]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:35 volumio sudo[8742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:05:35 volumio sudo[8742]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:35 volumio volumio[8473]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:05:35 volumio volumio[8473]: info: Adding Signal Path Element [object Object] Aug 29 14:05:35 volumio volumio[8473]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:05:35 volumio volumio[8473]: info: FusionDsp - ---- installed callbackRead Aug 29 14:05:35 volumio volumio[8473]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:35 volumio volumio[8473]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:05:35 volumio volumio[8473]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:05:35 volumio volumio[8473]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:05:35 volumio volumio[8473]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 14:05:35 volumio volumio[8473]: errno: -2, Aug 29 14:05:35 volumio volumio[8473]: code: 'ENOENT', Aug 29 14:05:35 volumio volumio[8473]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:35 volumio volumio[8473]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:35 volumio volumio[8473]: spawnargs: [ Aug 29 14:05:35 volumio volumio[8473]: '-p', Aug 29 14:05:35 volumio volumio[8473]: 9876, Aug 29 14:05:35 volumio volumio[8473]: '-o', Aug 29 14:05:35 volumio volumio[8473]: '/tmp/camilladsp.log', Aug 29 14:05:35 volumio volumio[8473]: '-l', Aug 29 14:05:35 volumio volumio[8473]: 'warn', Aug 29 14:05:35 volumio volumio[8473]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:05:35 volumio volumio[8473]: ] Aug 29 14:05:35 volumio volumio[8473]: } Aug 29 14:05:35 volumio volumio[8473]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:35 volumio sudo[8742]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:35 volumio sudo[8753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:04 Aug 29 14:05:35 volumio sudo[8753]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:35 volumio sudo[8753]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:05:35 volumio sudo[8505]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:05:35 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:35 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:05:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Aug 29 14:05:35 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:35 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:05:35 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:05:35 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:36 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:36 volumio volumio[8765]: info: ----- Volumio3 ---- Aug 29 14:05:36 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:36 volumio volumio[8765]: info: ----- System startup ---- Aug 29 14:05:36 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:36 volumio volumio[8765]: info: MYVOLUMIO Environment detected Aug 29 14:05:36 volumio volumio[8765]: info: Plugin folders cleanup Aug 29 14:05:36 volumio volumio[8765]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category audio_interface Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category miscellanea Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category music_service Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category plugins.json Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category system_controller Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category user_interface Aug 29 14:05:36 volumio volumio[8765]: info: Scanning into folder /data/plugins/ Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category audio_interface Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category music_service Aug 29 14:05:36 volumio volumio[8765]: info: Scanning category user_interface Aug 29 14:05:36 volumio volumio[8765]: info: Plugin folders cleanup completed Aug 29 14:05:36 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:36 volumio volumio[8765]: info: ----- Core plugins startup ---- Aug 29 14:05:36 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:05:36 volumio volumio[8765]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:05:36 volumio volumio[8765]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:05:36 volumio volumio[8765]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugins from folder /data/plugins/ Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "system"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "appearance"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "network"... Aug 29 14:05:36 volumio volumio[8765]: info: Refreshing Cached IP Addresses Aug 29 14:05:36 volumio sudo[8791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:36 volumio sudo[8791]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:36 volumio sudo[8791]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:36 volumio sudo[8793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:36 volumio sudo[8793]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "services"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "alsa_controller"... Aug 29 14:05:36 volumio sudo[8793]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:36 volumio sudo[8796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:05:36 volumio sudo[8796]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:36 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "wizard"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "networkfs"... Aug 29 14:05:36 volumio volumio[8765]: info: Starting Udev Watcher for removable devices Aug 29 14:05:36 volumio volumio[8765]: info: Mounting Device 7A6D-519E Aug 29 14:05:36 volumio sudo[8805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:36 volumio sudo[8805]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:36 volumio sudo[8805]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:36 volumio volumio[8765]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:36 volumio volumio[8765]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:36 volumio volumio[8765]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:36 volumio volumio[8765]: info: Ignoring mount for partition: boot Aug 29 14:05:36 volumio volumio[8765]: info: Ignoring mount for partition: volumio Aug 29 14:05:36 volumio volumio[8765]: info: Ignoring mount for partition: volumio_data Aug 29 14:05:36 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "upnp"... Aug 29 14:05:36 volumio volumio[8765]: info: [1724920536790] Starting Upmpd Daemon Aug 29 14:05:36 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "my_music"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "mpd"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "upnp_browser"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "alarm-clock"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "airplay_emulation"... Aug 29 14:05:36 volumio volumio[8765]: info: Starting Shairport Sync Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "last_100"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "webradio"... Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "i2s_dacs"... Aug 29 14:05:36 volumio volumio[8765]: info: I2S DAC not set, start Auto-detection Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "volumiodiscovery"... Aug 29 14:05:36 volumio volumio[8765]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:36 volumio volumio[8765]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:36 volumio volumio[8765]: *** WARNING *** For more information see Aug 29 14:05:36 volumio volumio[8765]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:36 volumio node[8765]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:36 volumio volumio[8765]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:36 volumio volumio[8765]: *** WARNING *** For more information see Aug 29 14:05:36 volumio node[8765]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:36 volumio node[8765]: *** WARNING *** For more information see Aug 29 14:05:36 volumio node[8765]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:36 volumio node[8765]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:36 volumio node[8765]: *** WARNING *** For more information see Aug 29 14:05:36 volumio volumio[8765]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:05:36 volumio volumio[8765]: info: Discovery: Started advertising with name: Volumio Aug 29 14:05:36 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:36 volumio volumio[8765]: info: Loading plugin "youtube2"... Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "ytmusic"... Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "outputs"... Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "albumart"... Aug 29 14:05:37 volumio volumio[8765]: info: Plugin example_plugin is not enabled Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "inputs"... Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "updater_comm"... Aug 29 14:05:37 volumio volumio[8765]: info: Plugin mpdemulation is not enabled Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "rest_api"... Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "websocket"... Aug 29 14:05:37 volumio volumio[8765]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "fusiondsp"... Aug 29 14:05:37 volumio volumio[8765]: Forking 3 albumart workers Aug 29 14:05:37 volumio volumio[8765]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:05:37 volumio volumio[8765]: info: Loading plugin "touch_display"... Aug 29 14:05:37 volumio volumio[8765]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:05:37 volumio volumio[8765]: info: Loading i18n strings for locale en Aug 29 14:05:37 volumio volumio[8765]: Updating browse sources language Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:37 volumio volumio[8765]: Starting albumart workers Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::initPlayerControls Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: Starting albumart workers Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: Express server listening on port 3000 Aug 29 14:05:37 volumio volumio[8765]: [Metrics] WebUI: 1s 625.88ms Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::resetVolumioState Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::getcurrentVolume Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:37 volumio volumio[8765]: Starting albumart workers Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:37 volumio volumio[8765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:05:37 volumio volumio[8765]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:05:37 volumio volumio[8765]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::pushState Aug 29 14:05:37 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::updateTrackBlock Aug 29 14:05:37 volumio volumio[8765]: info: CorePlayQueue::getTrackBlock Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:37 volumio volumio[8765]: info: Reloading queue from file Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::pushState Aug 29 14:05:37 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::setRandom null Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::pushState Aug 29 14:05:37 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:37 volumio volumio[8765]: info: Setting Device type: Raspberry PI Aug 29 14:05:37 volumio volumio[8765]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:05:37 volumio volumio[8765]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:05:37 volumio volumio[8765]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:05:37 volumio volumio[8765]: info: Completed loading Core Plugins Aug 29 14:05:37 volumio volumio[8765]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:37 volumio volumio[8765]: info: CoreStateMachine::pushState Aug 29 14:05:37 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:37 volumio volumio[8765]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:37 volumio volumio[8765]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:37 volumio volumio[8765]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:37 volumio volumio[8765]: info: Reading ALSA contributions from plugins. Aug 29 14:05:38 volumio volumio[8765]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:38 volumio volumio[8765]: info: Output device has changed, restarting MPD Aug 29 14:05:38 volumio volumio[8765]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio sudo[8885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:38 volumio sudo[8885]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:38 volumio sudo[8886]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8885]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:38 volumio volumio[8765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:38 volumio volumio[8765]: info: ___________ START PLUGINS ___________ Aug 29 14:05:38 volumio volumio[8765]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:05:38 volumio volumio[8765]: info: Creating MPD Configuration file Aug 29 14:05:38 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:38 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:38 volumio sudo[8894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:38 volumio sudo[8894]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:38 volumio volumio[8765]: info: [1724920538071] CoreMusicLibrary::Adding element Media Servers Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio sudo[8897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:38 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:38 volumio sudo[8897]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8894]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:05:38 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:38 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:38 volumio volumio[8765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:38 volumio volumio[8765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:38 volumio volumio[8765]: info: [1724920538113] CoreMusicLibrary::Adding element Last_100 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:38 volumio volumio[8765]: info: [1724920538114] CoreMusicLibrary::Adding element Webradio Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:38 volumio volumio[8765]: info: Initializing BBC Radios Aug 29 14:05:38 volumio sudo[8902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:38 volumio sudo[8902]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8902]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:38 volumio volumio[8765]: info: [1724920538147] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:38 volumio volumio[8765]: Cannot find translation for source YouTube2 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:38 volumio volumio[8765]: info: [1724920538150] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:38 volumio volumio[8765]: Cannot find translation for source YouTube2 Aug 29 14:05:38 volumio volumio[8765]: Cannot find translation for source YouTube Music Aug 29 14:05:38 volumio volumio[8765]: info: Loading i18n strings for locale en Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:05:38 volumio volumio[8765]: info: Loading i18n strings for locale en Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:38 volumio volumio[8765]: info: Volumio Calling Home Aug 29 14:05:38 volumio volumio[8765]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:38 volumio sudo[8922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:05:38 volumio sudo[8922]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:05:38 volumio sudo[8927]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8922]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio sudo[8925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:05:38 volumio sudo[8925]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio systemd[1]: Reloading. Aug 29 14:05:38 volumio volumio[8765]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:05:38 volumio volumio[8765]: info: Discovery: Found device Volumio Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:38 volumio volumio[8765]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:38 volumio volumio[8765]: info: Reading ALSA contributions from plugins. Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: No backlight interface detected. Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:05:38 volumio volumio[8765]: info: MPD Permissions set Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:38 volumio volumio[8765]: info: Discovery: Getting this device information Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:38 volumio volumio[8765]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:05:38 volumio sudo[8952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:05:38 volumio sudo[8952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8952]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: verbose: New Socket.io Connection to 192.168.31.7:3000 from 192.168.31.43 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 14:05:38 volumio volumio[8765]: info: Starting Shairport Sync Aug 29 14:05:38 volumio sudo[8955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:05:38 volumio sudo[8955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8955]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: Starting Shairport Sync Aug 29 14:05:38 volumio mpd[8909]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:38 volumio volumio[8765]: info: Starting Shairport Sync Aug 29 14:05:38 volumio sudo[8958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:38 volumio sudo[8958]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio sudo[8963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:38 volumio sudo[8963]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:05:38 volumio sudo[8965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:38 volumio sudo[8965]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:05:38 volumio volumio[8765]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:38 volumio volumio[8765]: info: Output device has changed, restarting MPD Aug 29 14:05:38 volumio sudo[8969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:38 volumio sudo[8969]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio sudo[8969]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio sudo[8972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:38 volumio sudo[8972]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:38 volumio volumio[8765]: info: MPD Permissions set Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:38 volumio volumio[8765]: info: Starting Shairport Sync Aug 29 14:05:38 volumio sudo[8982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:38 volumio sudo[8982]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 14:05:38 volumio volumio-remote-updater[868]: [2024-08-29 14:05:38] [connect] Successful connection Aug 29 14:05:38 volumio volumio-remote-updater[868]: [2024-08-29 14:05:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724920538 101 Aug 29 14:05:38 volumio volumio[8765]: 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: 6 Aug 29 14:05:38 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:38 volumio sudo[8927]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:05:38 volumio sudo[8897]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio sudo[8886]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio sudo[8985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:05:38 volumio sudo[8985]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio systemd[1]: Reloading. Aug 29 14:05:38 volumio volumio[8765]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:05:38 volumio volumio[8765]: assert.ok(self.idling) Aug 29 14:05:38 volumio volumio[8765]: error: The expression evaluated to a falsy value: Aug 29 14:05:38 volumio volumio[8765]: assert.ok(self.idling) Aug 29 14:05:38 volumio volumio[8765]: error: updateQueue error: null Aug 29 14:05:38 volumio volumio[8765]: info: MPD running with PID8909 Aug 29 14:05:38 volumio volumio[8765]: ,establishing connection Aug 29 14:05:38 volumio volumio[8765]: error: updateQueue error: null Aug 29 14:05:38 volumio volumio[8765]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 14:05:38 volumio volumio[8765]: info: Volumio called home Aug 29 14:05:38 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:38 volumio sudo[8925]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:38 volumio volumio[8765]: info: Received Get System Info Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:38 volumio volumio[8765]: info: Discovery: Getting this device information Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: Listing playlists Aug 29 14:05:38 volumio sudo[8985]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: Volumio Kiosk started. Aug 29 14:05:38 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:38 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:38 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:38 volumio sudo[8965]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:38 volumio sudo[8982]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:38 volumio sudo[8963]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio sudo[8958]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: Completed starting Core Plugins Aug 29 14:05:38 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:38 volumio volumio[8765]: info: ----- MyVolumio plugins startup ---- Aug 29 14:05:38 volumio volumio[8765]: info: ------------------------------------------- Aug 29 14:05:38 volumio volumio[8765]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:05:38 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:38 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:38 volumio volumio[8765]: info: Shairport-Sync Started Aug 29 14:05:38 volumio volumio[8765]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:05:38 volumio volumio[8765]: info: Shairport-Sync Started Aug 29 14:05:38 volumio volumio[8765]: info: Shairport-Sync Started Aug 29 14:05:38 volumio volumio[8765]: info: Shairport-Sync Started Aug 29 14:05:38 volumio sudo[9003]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:38 volumio sudo[9003]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: X display number found: 0 Aug 29 14:05:38 volumio sudo[9003]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:38 volumio volumio[8765]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: touch_display: X display number found: 0 Aug 29 14:05:39 volumio mpd[9009]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:39 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:39 volumio sudo[8972]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:39 volumio volumio[8765]: error: updateQueue error: null Aug 29 14:05:39 volumio volumio[8765]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:05:39 volumio volumio[8765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:39 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:39 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:39 volumio volumio[8765]: info: Received Get System Info Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:39 volumio volumio[8765]: info: Discovery: Getting this device information Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:39 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:39 volumio volumio[8765]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:39 volumio volumio[8765]: info: Listing playlists Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:39 volumio volumio[8765]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:40 volumio volumio[8765]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:40 volumio volumio[8765]: 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 Aug 29 14:05:40 volumio volumio[8765]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:40 volumio volumio[8765]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:05:40 volumio volumio[8765]: info: camilladsp service started and running in background, instance 1 Aug 29 14:05:40 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:40 volumio volumio[8765]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:40 volumio volumio[8765]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:05:40 volumio volumio[8765]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:40 volumio volumio[8765]: info: FusionDsp loaded Aug 29 14:05:40 volumio volumio[8765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:40 volumio sudo[9031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:05:40 volumio sudo[9031]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:40 volumio volumio[8765]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:05:40 volumio volumio[8765]: info: Adding Signal Path Element [object Object] Aug 29 14:05:40 volumio volumio[8765]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:05:40 volumio volumio[8765]: info: FusionDsp - ---- installed callbackRead Aug 29 14:05:40 volumio volumio[8765]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:40 volumio volumio[8765]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:05:40 volumio volumio[8765]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:05:40 volumio volumio[8765]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:05:40 volumio volumio[8765]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 14:05:40 volumio volumio[8765]: errno: -2, Aug 29 14:05:40 volumio volumio[8765]: code: 'ENOENT', Aug 29 14:05:40 volumio volumio[8765]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:40 volumio volumio[8765]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:40 volumio volumio[8765]: spawnargs: [ Aug 29 14:05:40 volumio volumio[8765]: '-p', Aug 29 14:05:40 volumio volumio[8765]: 9876, Aug 29 14:05:40 volumio volumio[8765]: '-o', Aug 29 14:05:40 volumio volumio[8765]: '/tmp/camilladsp.log', Aug 29 14:05:40 volumio volumio[8765]: '-l', Aug 29 14:05:40 volumio volumio[8765]: 'warn', Aug 29 14:05:40 volumio volumio[8765]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:05:40 volumio volumio[8765]: ] Aug 29 14:05:40 volumio volumio[8765]: } Aug 29 14:05:40 volumio volumio[8765]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:40 volumio sudo[9031]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:40 volumio sudo[9042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:04 Aug 29 14:05:40 volumio sudo[9042]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:40 volumio sudo[8796]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:40 volumio sudo[9042]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:40 volumio volumio-remote-updater[868]: [2024-08-29 14:05:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 14:05:40 volumio volumio-remote-updater[868]: [2024-08-29 14:05:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 14:05:40 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:05:40 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:05:40 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:40 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:40 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:05:40 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Aug 29 14:05:40 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:40 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:05:40 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:05:40 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:41 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:41 volumio volumio[9054]: info: ----- Volumio3 ---- Aug 29 14:05:41 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:41 volumio volumio[9054]: info: ----- System startup ---- Aug 29 14:05:41 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:41 volumio volumio[9054]: info: MYVOLUMIO Environment detected Aug 29 14:05:41 volumio volumio[9054]: info: Plugin folders cleanup Aug 29 14:05:41 volumio volumio[9054]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category audio_interface Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category miscellanea Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category music_service Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category plugins.json Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category system_controller Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category user_interface Aug 29 14:05:41 volumio volumio[9054]: info: Scanning into folder /data/plugins/ Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category audio_interface Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category music_service Aug 29 14:05:41 volumio volumio[9054]: info: Scanning category user_interface Aug 29 14:05:41 volumio volumio[9054]: info: Plugin folders cleanup completed Aug 29 14:05:41 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:41 volumio volumio[9054]: info: ----- Core plugins startup ---- Aug 29 14:05:41 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:05:41 volumio volumio[9054]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:05:41 volumio volumio[9054]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:05:41 volumio volumio[9054]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugins from folder /data/plugins/ Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "system"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "appearance"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "network"... Aug 29 14:05:41 volumio volumio[9054]: info: Refreshing Cached IP Addresses Aug 29 14:05:41 volumio sudo[9080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:41 volumio sudo[9080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:41 volumio sudo[9080]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:41 volumio sudo[9082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "services"... Aug 29 14:05:41 volumio sudo[9082]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "alsa_controller"... Aug 29 14:05:41 volumio sudo[9082]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:41 volumio sudo[9085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:05:41 volumio sudo[9085]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:41 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "wizard"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "networkfs"... Aug 29 14:05:41 volumio volumio[9054]: info: Starting Udev Watcher for removable devices Aug 29 14:05:41 volumio volumio[9054]: info: Mounting Device 7A6D-519E Aug 29 14:05:41 volumio sudo[9094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:41 volumio sudo[9094]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:41 volumio sudo[9094]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:41 volumio volumio[9054]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:41 volumio volumio[9054]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:41 volumio volumio[9054]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:41 volumio volumio[9054]: info: Ignoring mount for partition: boot Aug 29 14:05:41 volumio volumio[9054]: info: Ignoring mount for partition: volumio Aug 29 14:05:41 volumio volumio[9054]: info: Ignoring mount for partition: volumio_data Aug 29 14:05:41 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "upnp"... Aug 29 14:05:41 volumio volumio[9054]: info: [1724920541587] Starting Upmpd Daemon Aug 29 14:05:41 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "my_music"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "mpd"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "upnp_browser"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "alarm-clock"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "airplay_emulation"... Aug 29 14:05:41 volumio volumio[9054]: info: Starting Shairport Sync Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "last_100"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "webradio"... Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "i2s_dacs"... Aug 29 14:05:41 volumio volumio[9054]: info: I2S DAC not set, start Auto-detection Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "volumiodiscovery"... Aug 29 14:05:41 volumio volumio[9054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:41 volumio volumio[9054]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:41 volumio volumio[9054]: *** WARNING *** For more information see Aug 29 14:05:41 volumio volumio[9054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:41 volumio volumio[9054]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:41 volumio node[9054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:41 volumio volumio[9054]: *** WARNING *** For more information see Aug 29 14:05:41 volumio node[9054]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:41 volumio node[9054]: *** WARNING *** For more information see Aug 29 14:05:41 volumio node[9054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:41 volumio node[9054]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:41 volumio node[9054]: *** WARNING *** For more information see Aug 29 14:05:41 volumio volumio[9054]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:05:41 volumio volumio[9054]: info: Discovery: Started advertising with name: Volumio Aug 29 14:05:41 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:41 volumio volumio[9054]: info: Loading plugin "youtube2"... Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "ytmusic"... Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "outputs"... Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "albumart"... Aug 29 14:05:42 volumio volumio[9054]: info: Plugin example_plugin is not enabled Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "inputs"... Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "updater_comm"... Aug 29 14:05:42 volumio volumio[9054]: info: Plugin mpdemulation is not enabled Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "rest_api"... Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "websocket"... Aug 29 14:05:42 volumio volumio[9054]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "fusiondsp"... Aug 29 14:05:42 volumio volumio[9054]: Forking 3 albumart workers Aug 29 14:05:42 volumio volumio[9054]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:05:42 volumio volumio[9054]: info: Loading plugin "touch_display"... Aug 29 14:05:42 volumio volumio[9054]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:05:42 volumio volumio[9054]: info: Loading i18n strings for locale en Aug 29 14:05:42 volumio volumio[9054]: Updating browse sources language Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::initPlayerControls Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: Express server listening on port 3000 Aug 29 14:05:42 volumio volumio[9054]: [Metrics] WebUI: 1s 616.74ms Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::resetVolumioState Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::getcurrentVolume Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:42 volumio volumio[9054]: Starting albumart workers Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: Starting albumart workers Aug 29 14:05:42 volumio volumio[9054]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:05:42 volumio volumio[9054]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:05:42 volumio volumio[9054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::pushState Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::updateTrackBlock Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrackBlock Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:42 volumio volumio[9054]: Starting albumart workers Aug 29 14:05:42 volumio volumio[9054]: info: Reloading queue from file Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::pushState Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::setRandom null Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::pushState Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:42 volumio volumio[9054]: info: Setting Device type: Raspberry PI Aug 29 14:05:42 volumio volumio[9054]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:05:42 volumio volumio[9054]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:05:42 volumio volumio[9054]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:05:42 volumio volumio[9054]: info: Completed loading Core Plugins Aug 29 14:05:42 volumio volumio[9054]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:42 volumio volumio[9054]: info: CoreStateMachine::pushState Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:42 volumio volumio[9054]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:42 volumio volumio[9054]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:42 volumio volumio[9054]: info: Reading ALSA contributions from plugins. Aug 29 14:05:42 volumio volumio[9054]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:05:42 volumio volumio[9054]: info: Discovery: Found device Volumio Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:42 volumio volumio[9054]: info: Discovery: Getting this device information Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:42 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:42 volumio volumio[9054]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:42 volumio volumio[9054]: info: Output device has changed, restarting MPD Aug 29 14:05:42 volumio sudo[9165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:42 volumio sudo[9165]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:42 volumio volumio[9054]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:42 volumio sudo[9165]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:42 volumio sudo[9168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:42 volumio sudo[9168]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:42 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:42 volumio volumio[9054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:42 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:42 volumio volumio[9054]: info: ___________ START PLUGINS ___________ Aug 29 14:05:42 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:42 volumio volumio[9054]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:05:42 volumio volumio[9054]: info: Creating MPD Configuration file Aug 29 14:05:42 volumio sudo[9175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:42 volumio sudo[9175]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:42 volumio volumio[9054]: info: [1724920542877] CoreMusicLibrary::Adding element Media Servers Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:42 volumio sudo[9175]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:42 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:42 volumio sudo[9186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:42 volumio sudo[9186]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:42 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:05:42 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:42 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:42 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:42 volumio volumio[9054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:42 volumio volumio[9054]: info: [1724920542923] CoreMusicLibrary::Adding element Last_100 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:42 volumio sudo[9191]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:42 volumio sudo[9191]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:42 volumio volumio[9054]: info: [1724920542924] CoreMusicLibrary::Adding element Webradio Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:42 volumio sudo[9191]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:42 volumio volumio[9054]: info: Initializing BBC Radios Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:42 volumio volumio[9054]: info: [1724920542957] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:42 volumio volumio[9054]: Cannot find translation for source YouTube2 Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:42 volumio volumio[9054]: info: [1724920542959] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:42 volumio volumio[9054]: Cannot find translation for source YouTube2 Aug 29 14:05:42 volumio volumio[9054]: Cannot find translation for source YouTube Music Aug 29 14:05:42 volumio volumio[9054]: info: Loading i18n strings for locale en Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:42 volumio volumio[9054]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:05:42 volumio volumio[9054]: info: Loading i18n strings for locale en Aug 29 14:05:42 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:42 volumio volumio[9054]: info: Volumio Calling Home Aug 29 14:05:42 volumio volumio[9054]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:43 volumio sudo[9211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:05:43 volumio sudo[9211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio sudo[9214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:05:43 volumio sudo[9214]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio sudo[9217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:05:43 volumio sudo[9217]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio systemd[1]: Reloading. Aug 29 14:05:43 volumio sudo[9211]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio volumio[9054]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:43 volumio volumio[9054]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:43 volumio volumio[9054]: info: Reading ALSA contributions from plugins. Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: No backlight interface detected. Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:05:43 volumio volumio[9054]: info: MPD Permissions set Aug 29 14:05:43 volumio volumio[9054]: info: MPD Permissions set Aug 29 14:05:43 volumio volumio[9054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:05:43 volumio volumio[9054]: verbose: New Socket.io Connection to 192.168.31.7:3000 from 192.168.31.43 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 29 14:05:43 volumio volumio[9054]: info: Starting Shairport Sync Aug 29 14:05:43 volumio sudo[9243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:05:43 volumio sudo[9243]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio sudo[9243]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio sudo[9242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:05:43 volumio sudo[9242]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:43 volumio sudo[9242]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio volumio[9054]: info: Starting Shairport Sync Aug 29 14:05:43 volumio volumio[9054]: info: Starting Shairport Sync Aug 29 14:05:43 volumio sudo[9247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:43 volumio sudo[9247]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:43 volumio mpd[9196]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:43 volumio volumio[9054]: info: Received Get System Info Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:43 volumio volumio[9054]: info: Discovery: Getting this device information Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio sudo[9252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:43 volumio volumio[9054]: info: Listing playlists Aug 29 14:05:43 volumio sudo[9251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:43 volumio sudo[9252]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio sudo[9251]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:05:43 volumio volumio[9054]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:43 volumio volumio[9054]: info: Output device has changed, restarting MPD Aug 29 14:05:43 volumio sudo[9258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:43 volumio sudo[9258]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio volumio[9054]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:43 volumio sudo[9258]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio sudo[9261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:43 volumio sudo[9261]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio volumio[9054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:43 volumio volumio[9054]: info: MPD Permissions set Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 14:05:43 volumio volumio[9054]: info: Starting Shairport Sync Aug 29 14:05:43 volumio sudo[9271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:43 volumio sudo[9271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:43 volumio sudo[9186]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio sudo[9168]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio sudo[9214]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio systemd[1]: Reloading. Aug 29 14:05:43 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:43 volumio sudo[9217]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:43 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:43 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:43 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:43 volumio volumio[9054]: info: Volumio called home Aug 29 14:05:43 volumio sudo[9251]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:43 volumio sudo[9271]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:43 volumio sudo[9252]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio sudo[9247]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:05:43 volumio sudo[9293]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:43 volumio sudo[9293]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio sudo[9293]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio volumio[9054]: info: Shairport-Sync Started Aug 29 14:05:43 volumio volumio[9054]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:05:43 volumio volumio[9054]: info: Shairport-Sync Started Aug 29 14:05:43 volumio volumio[9054]: info: Shairport-Sync Started Aug 29 14:05:43 volumio volumio[9054]: info: Shairport-Sync Started Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:05:43 volumio volumio[9054]: error: Cannot initialize MPD Connection: MPD is not running Aug 29 14:05:43 volumio sudo[9301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:05:43 volumio sudo[9301]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:43 volumio sudo[9301]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: Volumio Kiosk started. Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: Completed starting Core Plugins Aug 29 14:05:43 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:43 volumio volumio[9054]: info: ----- MyVolumio plugins startup ---- Aug 29 14:05:43 volumio volumio[9054]: info: ------------------------------------------- Aug 29 14:05:43 volumio volumio[9054]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:05:43 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:43 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: X display number found: 0 Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: X display number found: 0 Aug 29 14:05:43 volumio mpd[9299]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:43 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:43 volumio sudo[9261]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:43 volumio volumio[9054]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:05:43 volumio volumio[9054]: assert.ok(self.idling) Aug 29 14:05:43 volumio volumio[9054]: error: The expression evaluated to a falsy value: Aug 29 14:05:43 volumio volumio[9054]: assert.ok(self.idling) Aug 29 14:05:43 volumio volumio[9054]: error: updateQueue error: null Aug 29 14:05:43 volumio volumio[9054]: error: updateQueue error: null Aug 29 14:05:43 volumio volumio[9054]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:05:44 volumio volumio[9054]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:44 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:05:44 volumio volumio[9054]: info: Received Get System Info Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:44 volumio volumio[9054]: info: Discovery: Getting this device information Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:44 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:44 volumio volumio[9054]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:44 volumio volumio[9054]: info: Listing playlists Aug 29 14:05:44 volumio volumio[9054]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:44 volumio volumio[9054]: 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 Aug 29 14:05:44 volumio volumio[9054]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:05:44 volumio volumio[9054]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:05:44 volumio volumio[9054]: info: camilladsp service started and running in background, instance 1 Aug 29 14:05:44 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:45 volumio volumio[9054]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:45 volumio volumio[9054]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:05:45 volumio volumio[9054]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:05:45 volumio volumio[9054]: info: FusionDsp loaded Aug 29 14:05:45 volumio volumio[9054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:45 volumio sudo[9326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:05:45 volumio volumio[9054]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:05:45 volumio sudo[9326]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:45 volumio volumio[9054]: info: Adding Signal Path Element [object Object] Aug 29 14:05:45 volumio volumio[9054]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:05:45 volumio volumio[9054]: info: FusionDsp - ---- installed callbackRead Aug 29 14:05:45 volumio volumio[9054]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:45 volumio volumio[9054]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:05:45 volumio volumio[9054]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:05:45 volumio volumio[9054]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:05:45 volumio volumio[9054]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 14:05:45 volumio volumio[9054]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 14:05:45 volumio volumio[9054]: at listOnTimeout (internal/timers.js:523:9) Aug 29 14:05:45 volumio volumio[9054]: at processTimers (internal/timers.js:497:7) { Aug 29 14:05:45 volumio volumio[9054]: errno: -2, Aug 29 14:05:45 volumio volumio[9054]: code: 'ENOENT', Aug 29 14:05:45 volumio volumio[9054]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:45 volumio volumio[9054]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:05:45 volumio volumio[9054]: spawnargs: [ Aug 29 14:05:45 volumio volumio[9054]: '-p', Aug 29 14:05:45 volumio volumio[9054]: 9876, Aug 29 14:05:45 volumio volumio[9054]: '-o', Aug 29 14:05:45 volumio volumio[9054]: '/tmp/camilladsp.log', Aug 29 14:05:45 volumio volumio[9054]: '-l', Aug 29 14:05:45 volumio volumio[9054]: 'warn', Aug 29 14:05:45 volumio volumio[9054]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:05:45 volumio volumio[9054]: ] Aug 29 14:05:45 volumio volumio[9054]: } Aug 29 14:05:45 volumio volumio[9054]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:45 volumio sudo[9326]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:45 volumio sudo[9337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:04 Aug 29 14:05:45 volumio sudo[9337]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:45 volumio sudo[9085]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:45 volumio sudo[9337]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:05:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:05:45 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:45 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:05:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Aug 29 14:05:45 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:05:45 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:05:45 volumio systemd[1]: Started dynamicswap service. Aug 29 14:05:45 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:05:45 volumio volumio-remote-updater[868]: [2024-08-29 14:05:45] [info] asio async_connect error: system:111 (Connection refused) Aug 29 14:05:45 volumio volumio-remote-updater[868]: [2024-08-29 14:05:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 29 14:05:45 volumio volumio-remote-updater[868]: [2024-08-29 14:05:45] [error] handle_connect error: Underlying Transport Error Aug 29 14:05:45 volumio volumio[9347]: info: ------------------------------------------- Aug 29 14:05:45 volumio volumio[9347]: info: ----- Volumio3 ---- Aug 29 14:05:45 volumio volumio[9347]: info: ------------------------------------------- Aug 29 14:05:45 volumio volumio[9347]: info: ----- System startup ---- Aug 29 14:05:45 volumio volumio[9347]: info: ------------------------------------------- Aug 29 14:05:45 volumio volumio[9347]: info: MYVOLUMIO Environment detected Aug 29 14:05:45 volumio volumio[9347]: info: Plugin folders cleanup Aug 29 14:05:45 volumio volumio[9347]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category audio_interface Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category miscellanea Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category music_service Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category plugins.json Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category system_controller Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category user_interface Aug 29 14:05:45 volumio volumio[9347]: info: Scanning into folder /data/plugins/ Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category audio_interface Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category music_service Aug 29 14:05:45 volumio volumio[9347]: info: Scanning category user_interface Aug 29 14:05:45 volumio volumio[9347]: info: Plugin folders cleanup completed Aug 29 14:05:45 volumio volumio[9347]: info: ------------------------------------------- Aug 29 14:05:45 volumio volumio[9347]: info: ----- Core plugins startup ---- Aug 29 14:05:45 volumio volumio[9347]: info: ------------------------------------------- Aug 29 14:05:45 volumio volumio[9347]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:05:45 volumio volumio[9347]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:05:45 volumio volumio[9347]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:05:45 volumio volumio[9347]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:05:45 volumio volumio[9347]: info: Loading plugins from folder /data/plugins/ Aug 29 14:05:45 volumio volumio[9347]: info: Loading plugin "system"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "appearance"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "network"... Aug 29 14:05:46 volumio volumio[9347]: info: Refreshing Cached IP Addresses Aug 29 14:05:46 volumio sudo[9375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:05:46 volumio sudo[9375]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:46 volumio sudo[9377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "services"... Aug 29 14:05:46 volumio sudo[9375]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:46 volumio sudo[9377]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "alsa_controller"... Aug 29 14:05:46 volumio sudo[9377]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:46 volumio sudo[9380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:05:46 volumio sudo[9380]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:46 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "wizard"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "networkfs"... Aug 29 14:05:46 volumio volumio[9347]: info: Starting Udev Watcher for removable devices Aug 29 14:05:46 volumio volumio[9347]: info: Mounting Device 7A6D-519E Aug 29 14:05:46 volumio sudo[9389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:46 volumio sudo[9389]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:46 volumio sudo[9389]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:46 volumio volumio[9347]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:46 volumio volumio[9347]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:05:46 volumio volumio[9347]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:05:46 volumio volumio[9347]: info: Ignoring mount for partition: boot Aug 29 14:05:46 volumio volumio[9347]: info: Ignoring mount for partition: volumio Aug 29 14:05:46 volumio volumio[9347]: info: Ignoring mount for partition: volumio_data Aug 29 14:05:46 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "upnp"... Aug 29 14:05:46 volumio volumio[9347]: info: [1724920546279] Starting Upmpd Daemon Aug 29 14:05:46 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "my_music"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "mpd"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "upnp_browser"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "alarm-clock"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "airplay_emulation"... Aug 29 14:05:46 volumio volumio[9347]: info: Starting Shairport Sync Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "last_100"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "webradio"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "i2s_dacs"... Aug 29 14:05:46 volumio volumio[9347]: info: I2S DAC not set, start Auto-detection Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "volumiodiscovery"... Aug 29 14:05:46 volumio volumio[9347]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:46 volumio node[9347]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:46 volumio node[9347]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:46 volumio volumio[9347]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:46 volumio volumio[9347]: *** WARNING *** For more information see Aug 29 14:05:46 volumio volumio[9347]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:46 volumio volumio[9347]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:46 volumio volumio[9347]: *** WARNING *** For more information see Aug 29 14:05:46 volumio node[9347]: *** WARNING *** For more information see Aug 29 14:05:46 volumio node[9347]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:05:46 volumio node[9347]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:05:46 volumio node[9347]: *** WARNING *** For more information see Aug 29 14:05:46 volumio volumio[9347]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:05:46 volumio volumio[9347]: info: Discovery: Started advertising with name: Volumio Aug 29 14:05:46 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "youtube2"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "ytmusic"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "outputs"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "albumart"... Aug 29 14:05:46 volumio volumio[9347]: info: Plugin example_plugin is not enabled Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "inputs"... Aug 29 14:05:46 volumio volumio[9347]: info: Loading plugin "updater_comm"... Aug 29 14:05:47 volumio volumio[9347]: info: Plugin mpdemulation is not enabled Aug 29 14:05:47 volumio volumio[9347]: info: Loading plugin "rest_api"... Aug 29 14:05:47 volumio volumio[9347]: info: Loading plugin "websocket"... Aug 29 14:05:47 volumio volumio[9347]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:05:47 volumio volumio[9347]: info: Loading plugin "fusiondsp"... Aug 29 14:05:47 volumio volumio[9347]: Forking 3 albumart workers Aug 29 14:05:47 volumio volumio[9347]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:05:47 volumio volumio[9347]: info: Loading plugin "touch_display"... Aug 29 14:05:47 volumio volumio[9347]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:05:47 volumio volumio[9347]: info: Loading i18n strings for locale en Aug 29 14:05:47 volumio volumio[9347]: Updating browse sources language Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:47 volumio volumio[9347]: Starting albumart workers Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: Starting albumart workers Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::initPlayerControls Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: Express server listening on port 3000 Aug 29 14:05:47 volumio volumio[9347]: [Metrics] WebUI: 1s 601.23ms Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::resetVolumioState Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::getcurrentVolume Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:47 volumio volumio[9347]: Starting albumart workers Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:05:47 volumio volumio[9347]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:05:47 volumio volumio[9347]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:05:47 volumio volumio[9347]: info: Reloading queue from file Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::pushState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::setRandom null Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::pushState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::pushState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::updateTrackBlock Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrackBlock Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:05:47 volumio volumio[9347]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:05:47 volumio volumio[9347]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:05:47 volumio volumio[9347]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:05:47 volumio volumio[9347]: info: Setting Device type: Raspberry PI Aug 29 14:05:47 volumio volumio[9347]: info: Completed loading Core Plugins Aug 29 14:05:47 volumio volumio[9347]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:47 volumio volumio[9347]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:47 volumio volumio[9347]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:47 volumio volumio[9347]: info: Reading ALSA contributions from plugins. Aug 29 14:05:47 volumio volumio[9347]: info: CoreStateMachine::pushState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioPushState Aug 29 14:05:47 volumio volumio[9347]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:47 volumio volumio[9347]: info: Output device has changed, restarting MPD Aug 29 14:05:47 volumio sudo[9460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:47 volumio sudo[9460]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio volumio[9347]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio sudo[9460]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio sudo[9467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:47 volumio sudo[9467]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:47 volumio volumio[9347]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:47 volumio volumio[9347]: info: ___________ START PLUGINS ___________ Aug 29 14:05:47 volumio volumio[9347]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:05:47 volumio volumio[9347]: info: Creating MPD Configuration file Aug 29 14:05:47 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:47 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:47 volumio sudo[9478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:47 volumio sudo[9478]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:47 volumio volumio[9347]: info: [1724920547530] CoreMusicLibrary::Adding element Media Servers Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio sudo[9478]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:47 volumio sudo[9481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:47 volumio sudo[9481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:05:47 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:47 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:47 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:47 volumio volumio[9347]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio sudo[9483]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:47 volumio sudo[9483]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio sudo[9483]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio volumio[9347]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:47 volumio volumio[9347]: info: [1724920547578] CoreMusicLibrary::Adding element Last_100 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:47 volumio volumio[9347]: info: [1724920547579] CoreMusicLibrary::Adding element Webradio Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:47 volumio volumio[9347]: info: Initializing BBC Radios Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:47 volumio volumio[9347]: info: [1724920547612] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:47 volumio volumio[9347]: Cannot find translation for source YouTube2 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:05:47 volumio volumio[9347]: info: [1724920547614] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:05:47 volumio volumio[9347]: Cannot find translation for source YouTube2 Aug 29 14:05:47 volumio volumio[9347]: Cannot find translation for source YouTube Music Aug 29 14:05:47 volumio volumio[9347]: info: Loading i18n strings for locale en Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:05:47 volumio volumio[9347]: info: Loading i18n strings for locale en Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:05:47 volumio volumio[9347]: info: Volumio Calling Home Aug 29 14:05:47 volumio volumio[9347]: info: Preparing to generate the ALSA configuration file Aug 29 14:05:47 volumio sudo[9506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:05:47 volumio sudo[9506]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio sudo[9509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:05:47 volumio sudo[9509]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio sudo[9512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:05:47 volumio sudo[9506]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio sudo[9512]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio systemd[1]: Reloading. Aug 29 14:05:47 volumio volumio[9347]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:05:47 volumio volumio[9347]: info: Discovery: Found device Volumio Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:05:47 volumio volumio[9347]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:05:47 volumio volumio[9347]: info: Reading ALSA contributions from plugins. Aug 29 14:05:47 volumio volumio[9347]: info: touch_display: No backlight interface detected. Aug 29 14:05:47 volumio volumio[9347]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:05:47 volumio volumio[9347]: info: MPD Permissions set Aug 29 14:05:47 volumio volumio[9347]: info: MPD Permissions set Aug 29 14:05:47 volumio volumio[9347]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:05:47 volumio sudo[9536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:05:47 volumio volumio[9347]: info: Starting Shairport Sync Aug 29 14:05:47 volumio sudo[9536]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio sudo[9536]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio volumio[9347]: info: Starting Shairport Sync Aug 29 14:05:47 volumio sudo[9541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:47 volumio sudo[9540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:05:47 volumio sudo[9541]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio sudo[9540]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio volumio[9347]: info: Starting Shairport Sync Aug 29 14:05:47 volumio sudo[9540]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:47 volumio sudo[9547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:47 volumio sudo[9547]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio volumio[9347]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:05:47 volumio volumio[9347]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:05:47 volumio volumio[9347]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:05:47 volumio volumio[9347]: info: Output device has changed, restarting MPD Aug 29 14:05:47 volumio sudo[9546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:47 volumio sudo[9546]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio volumio[9347]: info: Output device has changed, restarting Shairport Sync Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio sudo[9551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:05:47 volumio sudo[9553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:05:47 volumio sudo[9551]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio sudo[9553]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio mpd[9491]: Aug 29 14:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:05:47 volumio sudo[9551]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:47 volumio volumio[9347]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:05:47 volumio volumio[9347]: info: Discovery: Getting this device information Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:47 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:47 volumio volumio[9347]: info: MPD Permissions set Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:05:47 volumio volumio[9347]: info: Starting Shairport Sync Aug 29 14:05:47 volumio sudo[9566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:05:47 volumio sudo[9566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:47 volumio volumio[9347]: info: Volumio called home Aug 29 14:05:48 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:05:48 volumio sudo[9509]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio sudo[9467]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio sudo[9481]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio volumio[9347]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:05:48 volumio systemd[1]: Reloading. Aug 29 14:05:48 volumio volumio[9347]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:05:48 volumio volumio[9347]: assert.ok(self.idling) Aug 29 14:05:48 volumio volumio[9347]: error: The expression evaluated to a falsy value: Aug 29 14:05:48 volumio volumio[9347]: assert.ok(self.idling) Aug 29 14:05:48 volumio volumio[9347]: error: updateQueue error: null Aug 29 14:05:48 volumio volumio[9347]: info: MPD running with PID9491 Aug 29 14:05:48 volumio volumio[9347]: ,establishing connection Aug 29 14:05:48 volumio volumio[9347]: error: updateQueue error: null Aug 29 14:05:48 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:05:48 volumio volumio[9347]: verbose: New Socket.io Connection to 192.168.31.7:3000 from 192.168.31.43 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 14:05:48 volumio volumio[9347]: verbose: New Socket.io Connection to 192.168.31.7 from 192.168.31.43 UA: Mozilla/5.0 (Linux; Android 14; 2109119DI Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 14:05:48 volumio volumio[9347]: info: CoreCommandRouter::volumioGetState Aug 29 14:05:48 volumio volumio[9347]: info: CorePlayQueue::getTrack 0 Aug 29 14:05:48 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 14:05:48 volumio volumio[9347]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 14:05:48 volumio volumio[9347]: info: Starting Uninstall of plugin audio_interface - fusiondsp Aug 29 14:05:48 volumio volumio[9347]: info: Uninstalling plugin fusiondsp Aug 29 14:05:48 volumio volumio[9347]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:48 volumio volumio[9347]: TypeError: Cannot read property 'off' of undefined Aug 29 14:05:48 volumio volumio[9347]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Aug 29 14:05:48 volumio volumio[9347]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 29 14:05:48 volumio volumio[9347]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Aug 29 14:05:48 volumio volumio[9347]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Aug 29 14:05:48 volumio volumio[9347]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Aug 29 14:05:48 volumio volumio[9347]: at Socket.emit (events.js:315:20) Aug 29 14:05:48 volumio volumio[9347]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 14:05:48 volumio volumio[9347]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 29 14:05:48 volumio volumio[9347]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:05:48 volumio sudo[9512]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:05:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:05:48 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:05:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:48 volumio sudo[9593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:04 Aug 29 14:05:48 volumio sudo[9593]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:05:48 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:05:48 volumio sudo[9566]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:05:48 volumio sudo[9547]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio sudo[9541]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:05:48 volumio sudo[9546]: pam_unix(sudo:session): session closed for user root Aug 29 14:05:48 volumio sudo[9595]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:05:48 volumio sudo[9595]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:05:48 volumio sudo[9595]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"