-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2024-08-17 17:17:58 CEST. -- Aug 17 17:16:03 volumio volumio[2538]: info: Selecting previously unselected package g++-8. Aug 17 17:16:03 volumio volumio[2538]: info: Preparing to unpack .../17-g++-8_8.3.0-6_armhf.deb ... Aug 17 17:16:03 volumio volumio[2538]: info: Unpacking g++-8 (8.3.0-6) ... Aug 17 17:16:05 volumio volumio[2538]: info: Selecting previously unselected package g++. Aug 17 17:16:05 volumio volumio[2538]: info: Preparing to unpack .../18-g++_4%3a8.3.0-1_armhf.deb ... Aug 17 17:16:05 volumio volumio[2538]: info: Unpacking g++ (4:8.3.0-1) ... Aug 17 17:16:05 volumio volumio[2538]: info: Selecting previously unselected package make. Aug 17 17:16:05 volumio volumio[2538]: info: Preparing to unpack .../19-make_4.2.1-1.2_armhf.deb ... Aug 17 17:16:05 volumio volumio[2538]: info: Unpacking make (4.2.1-1.2) ... Aug 17 17:16:06 volumio volumio[2538]: info: Selecting previously unselected package patch. Aug 17 17:16:06 volumio volumio[2538]: info: Preparing to unpack .../20-patch_2.7.6-3+deb10u1_armhf.deb ... Aug 17 17:16:06 volumio volumio[2538]: info: Unpacking patch (2.7.6-3+deb10u1) ... Aug 17 17:16:06 volumio volumio[2538]: info: Selecting previously unselected package dpkg-dev. Aug 17 17:16:06 volumio volumio[2538]: info: Preparing to unpack .../21-dpkg-dev_1.19.8_all.deb ... Aug 17 17:16:06 volumio volumio[2538]: info: Unpacking dpkg-dev (1.19.8) ... Aug 17 17:16:06 volumio volumio[2538]: info: Selecting previously unselected package build-essential. Aug 17 17:16:06 volumio volumio[2538]: info: Preparing to unpack .../22-build-essential_12.6_armhf.deb ... Aug 17 17:16:06 volumio volumio[2538]: info: Unpacking build-essential (12.6) ... Aug 17 17:16:06 volumio volumio[2538]: info: Selecting previously unselected package dh-python. Aug 17 17:16:06 volumio volumio[2538]: info: Preparing to unpack .../23-dh-python_3.20190308_all.deb ... Aug 17 17:16:06 volumio volumio[2538]: info: Unpacking dh-python (3.20190308) ... Aug 17 17:16:06 volumio volumio[2538]: info: Selecting previously unselected package libfakeroot:armhf. Aug 17 17:16:06 volumio volumio[2538]: info: Preparing to unpack .../24-libfakeroot_1.23-1_armhf.deb ... Aug 17 17:16:06 volumio volumio[2538]: info: Unpacking libfakeroot:armhf (1.23-1) ... Aug 17 17:16:06 volumio volumio[2538]: info: Selecting previously unselected package fakeroot. Aug 17 17:16:06 volumio volumio[2538]: info: Preparing to unpack .../25-fakeroot_1.23-1_armhf.deb ... Aug 17 17:16:06 volumio volumio[2538]: info: Unpacking fakeroot (1.23-1) ... Aug 17 17:16:07 volumio volumio[2538]: info: Selecting previously unselected package libalgorithm-diff-perl. Aug 17 17:16:07 volumio volumio[2538]: info: Preparing to unpack .../26-libalgorithm-diff-perl_1.19.03-2_all.deb ... Aug 17 17:16:07 volumio volumio[2538]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Aug 17 17:16:07 volumio volumio[2538]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Aug 17 17:16:07 volumio volumio[2538]: info: Preparing to unpack .../27-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Aug 17 17:16:07 volumio volumio[2538]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Aug 17 17:16:07 volumio volumio[2538]: info: Selecting previously unselected package libalgorithm-merge-perl. Aug 17 17:16:07 volumio volumio[2538]: info: Preparing to unpack .../28-libalgorithm-merge-perl_0.08-3_all.deb ... Aug 17 17:16:07 volumio volumio[2538]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Aug 17 17:16:07 volumio volumio[2538]: info: Selecting previously unselected package libexpat1-dev:armhf. Aug 17 17:16:07 volumio volumio[2538]: info: Preparing to unpack .../29-libexpat1-dev_2.2.6-2+deb10u4_armhf.deb ... Aug 17 17:16:07 volumio volumio[2538]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Aug 17 17:16:07 volumio volumio[2538]: info: Selecting previously unselected package libpython3.7-dev:armhf. Aug 17 17:16:07 volumio volumio[2538]: info: Preparing to unpack .../30-libpython3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Aug 17 17:16:07 volumio volumio[2538]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Aug 17 17:16:13 volumio volumio[2538]: info: Aug 17 17:16:13 volumio volumio[2538]: ---------------------------- MPD announces system playlist update Aug 17 17:16:13 volumio volumio[2538]: info: Ignoring MPD Status Update Aug 17 17:16:13 volumio volumio[2538]: info: Aug 17 17:16:13 volumio volumio[2538]: ---------------------------- MPD announces state update: player Aug 17 17:16:13 volumio volumio[2538]: info: ControllerMpd::getState Aug 17 17:16:13 volumio volumio[2538]: verbose: ControllerMpd::sendMpdCommand status Aug 17 17:16:13 volumio volumio[2538]: info: ------------------------------ 6ms Aug 17 17:16:13 volumio volumio[2538]: info: sendMpdCommand status took 3 milliseconds Aug 17 17:16:13 volumio volumio[2538]: verbose: ControllerMpd::parseState Aug 17 17:16:13 volumio volumio[2538]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 17:16:13 volumio volumio[2538]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 17:16:13 volumio volumio[2538]: verbose: ControllerMpd::parseTrackInfo Aug 17 17:16:13 volumio volumio[2538]: info: ControllerMpd::pushState Aug 17 17:16:13 volumio volumio[2538]: info: CoreCommandRouter::servicePushState Aug 17 17:16:13 volumio volumio[2538]: info: CorePlayQueue::getTrack 0 Aug 17 17:16:13 volumio volumio[2538]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139246,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"186 Kbps","isStreaming":false,"title":"LOLA YOUNG - MESSY","artist":"Néo Radio","album":null,"uri":"https://carina.streamerr.co:8006/stream","trackType":""} Aug 17 17:16:13 volumio volumio[2538]: verbose: CURRENT POSITION 0 Aug 17 17:16:13 volumio volumio[2538]: info: CoreStateMachine::syncState stateService play Aug 17 17:16:13 volumio volumio[2538]: info: CoreStateMachine::syncState currentStatus play Aug 17 17:16:13 volumio volumio[2538]: info: Received an update from plugin. extracting info from payload Aug 17 17:16:13 volumio volumio[2538]: info: CoreStateMachine::pushState Aug 17 17:16:13 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 17:16:13 volumio volumio[2538]: info: CoreCommandRouter::volumioPushState Aug 17 17:16:13 volumio volumio[2538]: info: CoreStateMachine::pushState Aug 17 17:16:13 volumio volumio[2538]: info: CoreCommandRouter::volumioPushState Aug 17 17:16:13 volumio volumio[2538]: info: ------------------------------ 24ms Aug 17 17:16:25 volumio volumio[2538]: info: Selecting previously unselected package libpython3-dev:armhf. Aug 17 17:16:25 volumio volumio[2538]: info: Preparing to unpack .../31-libpython3-dev_3.7.3-1_armhf.deb ... Aug 17 17:16:25 volumio volumio[2538]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Aug 17 17:16:25 volumio volumio[2538]: info: Selecting previously unselected package python-pip-whl. Aug 17 17:16:25 volumio volumio[2538]: info: Preparing to unpack .../32-python-pip-whl_18.1-5_all.deb ... Aug 17 17:16:25 volumio volumio[2538]: info: Unpacking python-pip-whl (18.1-5) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-multidict. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../33-python3-multidict_4.5.2-1_armhf.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-multidict (4.5.2-1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-yarl. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../34-python3-yarl_1.3.0-1_armhf.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-yarl (1.3.0-1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-async-timeout. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../35-python3-async-timeout_3.0.1-1_all.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-async-timeout (3.0.1-1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-attr. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../36-python3-attr_18.2.0-1_all.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-attr (18.2.0-1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-aiohttp. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../37-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-asn1crypto. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../38-python3-asn1crypto_0.24.0-1_all.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-cffi-backend. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../39-python3-cffi-backend_1.12.2-1_armhf.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Aug 17 17:16:26 volumio volumio[2538]: info: Selecting previously unselected package python3-crypto. Aug 17 17:16:26 volumio volumio[2538]: info: Preparing to unpack .../40-python3-crypto_2.6.1-9+b1_armhf.deb ... Aug 17 17:16:26 volumio volumio[2538]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-cryptography. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../41-python3-cryptography_2.6.1-3+deb10u2_armhf.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-cryptography (2.6.1-3+deb10u2) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-dbus. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../42-python3-dbus_1.2.8-3_armhf.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-dbus (1.2.8-3) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3.7-dev. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../43-python3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3.7-dev (3.7.3-2+deb10u3) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-dev. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../44-python3-dev_3.7.3-1_armhf.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-dev (3.7.3-1) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-entrypoints. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../45-python3-entrypoints_0.3-1_all.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-entrypoints (0.3-1) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-gi. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../46-python3-gi_3.30.4-1_armhf.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-gi (3.30.4-1) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-secretstorage. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../47-python3-secretstorage_2.3.1-2_all.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-secretstorage (2.3.1-2) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-keyring. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../48-python3-keyring_17.1.1-1_all.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-keyring (17.1.1-1) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-keyrings.alt. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../49-python3-keyrings.alt_3.1.1-1_all.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Aug 17 17:16:27 volumio volumio[2538]: info: Selecting previously unselected package python3-pip. Aug 17 17:16:27 volumio volumio[2538]: info: Preparing to unpack .../50-python3-pip_18.1-5_all.deb ... Aug 17 17:16:27 volumio volumio[2538]: info: Unpacking python3-pip (18.1-5) ... Aug 17 17:16:28 volumio volumio[2538]: info: Selecting previously unselected package python3-setuptools. Aug 17 17:16:28 volumio volumio[2538]: info: Preparing to unpack .../51-python3-setuptools_40.8.0-1_all.deb ... Aug 17 17:16:28 volumio volumio[2538]: info: Unpacking python3-setuptools (40.8.0-1) ... Aug 17 17:16:28 volumio volumio[2538]: info: Selecting previously unselected package python3-wheel. Aug 17 17:16:28 volumio volumio[2538]: info: Preparing to unpack .../52-python3-wheel_0.32.3-2_all.deb ... Aug 17 17:16:28 volumio volumio[2538]: info: Unpacking python3-wheel (0.32.3-2) ... Aug 17 17:16:28 volumio volumio[2538]: info: Selecting previously unselected package python3-xdg. Aug 17 17:16:28 volumio volumio[2538]: info: Preparing to unpack .../53-python3-xdg_0.25-5_all.deb ... Aug 17 17:16:28 volumio volumio[2538]: info: Unpacking python3-xdg (0.25-5) ... Aug 17 17:16:28 volumio volumio[2538]: info: Setting up python3-entrypoints (0.3-1) ... Aug 17 17:16:29 volumio volumio[2538]: info: Setting up dh-python (3.20190308) ... Aug 17 17:16:29 volumio volumio[2538]: info: Setting up python3-attr (18.2.0-1) ... Aug 17 17:16:30 volumio volumio[2538]: info: Setting up python3-dbus (1.2.8-3) ... Aug 17 17:16:32 volumio volumio[2538]: info: Setting up python3-setuptools (40.8.0-1) ... Aug 17 17:16:34 volumio volumio[2538]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Aug 17 17:16:34 volumio volumio[2538]: info: Setting up binutils-common:armhf (2.31.1-16) ... Aug 17 17:16:34 volumio volumio[2538]: info: Setting up python3-xdg (0.25-5) ... Aug 17 17:16:34 volumio volumio[2538]: info: Setting up bzip2 (1.0.6-9.2~deb10u1) ... Aug 17 17:16:34 volumio volumio[2538]: info: Setting up python3-wheel (0.32.3-2) ... Aug 17 17:16:35 volumio volumio[2538]: info: Setting up python3-multidict (4.5.2-1) ... Aug 17 17:16:35 volumio volumio[2538]: info: Setting up libfakeroot:armhf (1.23-1) ... Aug 17 17:16:35 volumio volumio[2538]: info: Setting up python3-async-timeout (3.0.1-1) ... Aug 17 17:16:36 volumio volumio[2538]: info: Setting up fakeroot (1.23-1) ... Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Aug 17 17:16:36 volumio volumio[2538]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Aug 17 17:16:36 volumio volumio[2538]: info: Setting up libasan5:armhf (8.3.0-6) ... Aug 17 17:16:36 volumio volumio[2538]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Aug 17 17:16:36 volumio volumio[2538]: info: Setting up make (4.2.1-1.2) ... Aug 17 17:16:36 volumio volumio[2538]: info: Setting up libmpfr6:armhf (4.0.2-1) ... Aug 17 17:16:36 volumio volumio[2538]: info: Setting up python3-gi (3.30.4-1) ... Aug 17 17:16:36 volumio volumio[2538]: verbose: New Socket.io Connection to 192.168.11.225 from 192.168.12.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 5 Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetVisibleSources Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 17 17:16:36 volumio volumio[2538]: info: Received Get System Info Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 17:16:36 volumio volumio[2538]: info: Discovery: Getting this device information Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:16:36 volumio volumio[2538]: info: Listing playlists Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetQueue Aug 17 17:16:36 volumio volumio[2538]: info: CoreStateMachine::getQueue Aug 17 17:16:36 volumio volumio[2538]: info: CorePlayQueue::getQueue Aug 17 17:16:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 17 17:16:37 volumio volumio[2538]: info: Setting up libmpc3:armhf (1.1.0-1) ... Aug 17 17:16:37 volumio volumio[2538]: info: Setting up patch (2.7.6-3+deb10u1) ... Aug 17 17:16:37 volumio volumio[2538]: info: Setting up libubsan1:armhf (8.3.0-6) ... Aug 17 17:16:37 volumio volumio[2538]: info: Setting up libisl19:armhf (0.20-2) ... Aug 17 17:16:37 volumio volumio[2538]: info: Setting up python3-crypto (2.6.1-9+b1) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up python-pip-whl (18.1-5) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up libbinutils:armhf (2.31.1-16) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up cpp-8 (8.3.0-6) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up binutils-arm-linux-gnueabihf (2.31.1-16) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up python3-yarl (1.3.0-1) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Aug 17 17:16:38 volumio volumio[2538]: info: Setting up python3-asn1crypto (0.24.0-1) ... Aug 17 17:16:39 volumio volumio[2538]: info: Setting up libcc1-0:armhf (8.3.0-6) ... Aug 17 17:16:39 volumio volumio[2538]: info: Setting up python3-cffi-backend (1.12.2-1) ... Aug 17 17:16:39 volumio volumio[2538]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Aug 17 17:16:39 volumio volumio[2538]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Aug 17 17:16:39 volumio volumio[2538]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... Aug 17 17:16:40 volumio volumio[2538]: info: Setting up binutils (2.31.1-16) ... Aug 17 17:16:40 volumio volumio[2538]: info: Setting up python3.7-dev (3.7.3-2+deb10u3) ... Aug 17 17:16:40 volumio volumio[2538]: info: Setting up dpkg-dev (1.19.8) ... Aug 17 17:16:40 volumio volumio[2538]: info: Setting up python3-cryptography (2.6.1-3+deb10u2) ... Aug 17 17:16:41 volumio volumio[2538]: info: Setting up python3-pip (18.1-5) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up libgcc-8-dev:armhf (8.3.0-6) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up cpp (4:8.3.0-1) ... Aug 17 17:16:42 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:16:42 volumio volumio[2538]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up gcc-8 (8.3.0-6) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up gcc (4:8.3.0-1) ... Aug 17 17:16:42 volumio volumio[2538]: info: Setting up python3-secretstorage (2.3.1-2) ... Aug 17 17:16:43 volumio volumio[2538]: info: Setting up python3-dev (3.7.3-1) ... Aug 17 17:16:43 volumio volumio[2538]: info: Setting up g++-8 (8.3.0-6) ... Aug 17 17:16:43 volumio volumio[2538]: info: Setting up python3-keyring (17.1.1-1) ... Aug 17 17:16:43 volumio volumio[2538]: info: Setting up g++ (4:8.3.0-1) ... Aug 17 17:16:43 volumio volumio[2538]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Aug 17 17:16:43 volumio volumio[2538]: info: Setting up build-essential (12.6) ... Aug 17 17:16:43 volumio volumio[2538]: info: Processing triggers for libc-bin (2.28-10+deb10u1) ... Aug 17 17:16:44 volumio sudo[6259]: pam_unix(sudo:session): session closed for user root Aug 17 17:16:44 volumio sudo[7069]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Aug 17 17:16:44 volumio sudo[7069]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:16:44 volumio volumio[2538]: info: lib/systemd/system/fusiondsp.service Aug 17 17:16:44 volumio sudo[7069]: pam_unix(sudo:session): session closed for user root Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:16:45 volumio volumio[2538]: info: Retrieving Cloud Streaming UI Aug 17 17:16:45 volumio volumio[2538]: info: Getting Tidal Cloud Configuration Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:16:45 volumio volumio[2538]: info: Getting Qobuz Cloud Configuration Aug 17 17:16:45 volumio volumio[2538]: info: Asking plugin for UI Config Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:16:45 volumio volumio[2538]: info: Getting Spotify Cloud Configuration Aug 17 17:16:45 volumio volumio[2538]: info: Asking plugin for UI Config Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:16:45 volumio volumio[2538]: info: Saving Spotify Acccount Aug 17 17:16:45 volumio volumio[2538]: info: Got Tidal Cloud Configuration Aug 17 17:16:45 volumio volumio[2538]: info: Got it Aug 17 17:16:45 volumio volumio[2538]: info: Got it Aug 17 17:16:45 volumio volumio[2538]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:16:45 volumio volumio[2538]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 17:16:45 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 17 17:16:46 volumio volumio[2538]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Aug 17 17:16:46 volumio volumio[2538]: info: more info at http://www.winimage.com/zLibDll/unzip.html Aug 17 17:16:46 volumio volumio[2538]: info: Aug 17 17:16:46 volumio volumio[2538]: info: cgui-1.0.0.zip opened Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/LICENSE.txt Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/README.md Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/build/ Aug 17 17:16:46 volumio sudo[7076]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/robots.txt Aug 17 17:16:46 volumio sudo[7076]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/logo512.png Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/asset-manifest.json Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/index.html Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/.put_statics_here Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/favicon.ico Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/logo192.png Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Aug 17 17:16:46 volumio sudo[7076]: pam_unix(sudo:session): session closed for user root Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/manifest.json Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/css-variables.css Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/service-worker.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/build/static/ Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/build/static/css/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Aug 17 17:16:46 volumio sudo[7078]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Aug 17 17:16:46 volumio sudo[7078]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/main.1503f6cd.css Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Aug 17 17:16:46 volumio sudo[7078]: pam_unix(sudo:session): session closed for user root Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/build/static/media/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/build/static/js/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.71e11955.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Aug 17 17:16:46 volumio sudo[7080]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Aug 17 17:16:46 volumio sudo[7080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.71e11955.js.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/config/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/config/camillagui.yml Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/config/gui-config.yml Aug 17 17:16:46 volumio sudo[7080]: pam_unix(sudo:session): session closed for user root Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/backend/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/filters_test.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/version.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/filters.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/settings.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/views.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/filterdefaults.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__init__.py Aug 17 17:16:46 volumio volumio[2538]: info: creating directory: cgui/backend/__pycache__/ Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/routes.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/backend/filemanagement.py Aug 17 17:16:46 volumio volumio[2538]: info: extracting: cgui/main.py Aug 17 17:16:49 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 17:16:50 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:16:51 volumio volumio[2538]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Aug 17 17:16:51 volumio volumio[2538]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-8ey93xs0 Aug 17 17:16:53 volumio volumio[2538]: info: Collecting PyYAML (from camilladsp==1.0.0) Aug 17 17:16:54 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Aug 17 17:16:55 volumio volumio[2538]: info: Installing build dependencies: started Aug 17 17:16:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 17:16:56 volumio volumio[2538]: info: Preload queue cleared Aug 17 17:17:03 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:06 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 17:17:06 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 17:17:09 volumio go-librespot[5957]: time="2024-08-17T17:17:09+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Aug 17 17:17:09 volumio volumio[2538]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 17 17:17:09 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 17:17:09 volumio sudo[7164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Aug 17 17:17:09 volumio sudo[7164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:09 volumio volumio[2538]: info: Disabling plugin spop Aug 17 17:17:09 volumio volumio[2538]: info: Done. Aug 17 17:17:09 volumio volumio[2538]: info: Connection to go-librespot Websocket closed Aug 17 17:17:09 volumio systemd[1]: Stopping go-librespot Daemon... Aug 17 17:17:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 17 17:17:09 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 17 17:17:09 volumio systemd[1]: Stopped go-librespot Daemon. Aug 17 17:17:09 volumio sudo[7164]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:10 volumio volumio[2538]: info: Enabling plugin spop Aug 17 17:17:10 volumio volumio[2538]: info: Loading plugin "spop"... Aug 17 17:17:10 volumio volumio[2538]: info: PLUGIN START: spop Aug 17 17:17:10 volumio volumio[2538]: info: Creating Spotify config file Aug 17 17:17:10 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:10 volumio volumio[2538]: info: Done. Aug 17 17:17:10 volumio volumio[2538]: info: Spotify config file written Aug 17 17:17:10 volumio sudo[7168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 17 17:17:10 volumio sudo[7168]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:10 volumio systemd[1]: Started go-librespot Daemon. Aug 17 17:17:10 volumio sudo[7168]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:10 volumio go-librespot[7170]: Librespot-go daemon starting... Aug 17 17:17:10 volumio go-librespot[7170]: time="2024-08-17T17:17:10+02:00" level=info msg="generated new device id: 2594aa76269ff8a00ebc19a2bc84aa05664220f2" Aug 17 17:17:10 volumio go-librespot[7170]: time="2024-08-17T17:17:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 17 17:17:10 volumio go-librespot[7170]: time="2024-08-17T17:17:10+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 17 17:17:10 volumio go-librespot[7170]: time="2024-08-17T17:17:10+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 17 17:17:10 volumio go-librespot[7170]: time="2024-08-17T17:17:10+02:00" level=debug msg="zeroconf server listening on port 44603" Aug 17 17:17:11 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:12 volumio volumio[2538]: info: Installing build dependencies: finished with status 'done' Aug 17 17:17:13 volumio volumio[2538]: info: go-librespot daemon successfully initialized Aug 17 17:17:16 volumio volumio[2538]: info: Initializing connection to go-librespot Websocket Aug 17 17:17:16 volumio go-librespot[7170]: time="2024-08-17T17:17:16+02:00" level=debug msg="new websocket client" Aug 17 17:17:16 volumio volumio[2538]: info: Connection to go-librespot Websocket established Aug 17 17:17:17 volumio volumio[2538]: info: Received OAUTH Data Aug 17 17:17:17 volumio volumio[2538]: info: Executing Spotify Oauth Login Aug 17 17:17:17 volumio volumio[2538]: info: Saving Spotify Refresh Token Aug 17 17:17:17 volumio volumio[2538]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 17 17:17:17 volumio volumio[2538]: SPOTIFY: BQCUHdFCFAwfobmZ5bc2XtsgfwzbU_dCUr1mHjUvnyRruzflCgt6F8BzIE5ynNBvMQxAQ2UzSl-u_FGxO0bjjwtLNPNsNdh96bONsBhxOHEFEMfxMIL6O7GVZ1AHylBktcixl5i59_UC61cJbrP4GFcC2zmrxr-Zz04XhSnjR31DuFJttstYAn-v8juyGDDdTwvWntlTICC-snw-r7T0hYUguexWI7XA5eiBCpEZgPuh2Xs7xDg Aug 17 17:17:17 volumio volumio[2538]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 17 17:17:17 volumio volumio[2538]: info: New Spotify access token = BQCUHdFCFAwfobmZ5bc2XtsgfwzbU_dCUr1mHjUvnyRruzflCgt6F8BzIE5ynNBvMQxAQ2UzSl-u_FGxO0bjjwtLNPNsNdh96bONsBhxOHEFEMfxMIL6O7GVZ1AHylBktcixl5i59_UC61cJbrP4GFcC2zmrxr-Zz04XhSnjR31DuFJttstYAn-v8juyGDDdTwvWntlTICC-snw-r7T0hYUguexWI7XA5eiBCpEZgPuh2Xs7xDg Aug 17 17:17:17 volumio volumio[2538]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 17 17:17:18 volumio sudo[7192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 17:17:18 volumio sudo[7192]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:18 volumio sudo[7192]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:18 volumio sudo[7194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 17:17:18 volumio sudo[7194]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:18 volumio volumio[2538]: SPOTIFY: User informations: {"display_name":"tom7182","external_urls":{"spotify":"https://open.spotify.com/user/tom7182"},"href":"https://api.spotify.com/v1/users/tom7182","id":"tom7182","images":[],"type":"user","uri":"spotify:user:tom7182","followers":{"href":null,"total":0},"country":"FR","product":"free","explicit_content":{"filter_enabled":true,"filter_locked":false},"email":"thomas.boudard@gmail.com"} Aug 17 17:17:18 volumio volumio[2538]: info: Creating Spotify config file Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:18 volumio sudo[7194]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:18 volumio volumio[2538]: info: Spotify config file written Aug 17 17:17:18 volumio sudo[7200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 17 17:17:18 volumio sudo[7200]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:18 volumio systemd[1]: Stopping go-librespot Daemon... Aug 17 17:17:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 17 17:17:18 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 17 17:17:18 volumio systemd[1]: Stopped go-librespot Daemon. Aug 17 17:17:18 volumio systemd[1]: Started go-librespot Daemon. Aug 17 17:17:18 volumio sudo[7200]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:18 volumio volumio[2538]: info: Connection to go-librespot Websocket closed Aug 17 17:17:18 volumio go-librespot[7202]: Librespot-go daemon starting... Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=info msg="generated new device id: 07a288c65281f70ef4a0e408088bc319ff6f0155" Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="stored credentials not found" Aug 17 17:17:18 volumio volumio[2538]: verbose: New Socket.io Connection to 192.168.11.225 from 192.168.12.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 17 17:17:18 volumio volumio[2538]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 17 17:17:18 volumio volumio[2538]: SPOTIFY: BQDSq3CtQXmOE8uMzY6rUUtE5gVaQ9fYY-yFKNcLEV_tDQ5g3eFCFvdZ6CrNlie8YC3Eb5Bnexoef7mb72d1ELoxwtCTA5gT9y01uC6JKj-OCEIFgplWv2Zfgih93VtxyZl07bgNYzZfbTWtTIQGg7DrW8pzjwSlVjk2hogwlZwx33SKfTOLxgtOLiewHDa-TJlCe5RrMlhCZnAKdDQKjCNiCyigkWwjuZWSYNZiblTndV8743g Aug 17 17:17:18 volumio volumio[2538]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Aug 17 17:17:18 volumio volumio[2538]: info: New Spotify access token = BQDSq3CtQXmOE8uMzY6rUUtE5gVaQ9fYY-yFKNcLEV_tDQ5g3eFCFvdZ6CrNlie8YC3Eb5Bnexoef7mb72d1ELoxwtCTA5gT9y01uC6JKj-OCEIFgplWv2Zfgih93VtxyZl07bgNYzZfbTWtTIQGg7DrW8pzjwSlVjk2hogwlZwx33SKfTOLxgtOLiewHDa-TJlCe5RrMlhCZnAKdDQKjCNiCyigkWwjuZWSYNZiblTndV8743g Aug 17 17:17:18 volumio volumio[2538]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 17 17:17:18 volumio volumio[2538]: SPOTIFY: User informations: {"display_name":"tom7182","external_urls":{"spotify":"https://open.spotify.com/user/tom7182"},"href":"https://api.spotify.com/v1/users/tom7182","id":"tom7182","images":[],"type":"user","uri":"spotify:user:tom7182","followers":{"href":null,"total":0},"country":"FR","product":"free","explicit_content":{"filter_enabled":true,"filter_locked":false},"email":"thomas.boudard@gmail.com"} Aug 17 17:17:18 volumio volumio[2538]: info: Spotify Successfully logged in Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="zeroconf server listening on port 43149" Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 17:17:18 volumio volumio[2538]: info: [1723907838464] CoreMusicLibrary::Adding element Spotify Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 17:17:18 volumio volumio[2538]: Cannot find translation for source Spotify Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::volumioGetVisibleSources Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 17 17:17:18 volumio volumio[2538]: info: Received Get System Info Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 17:17:18 volumio volumio[2538]: info: Discovery: Getting this device information Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:18 volumio volumio[2538]: info: Listing playlists Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 17 17:17:18 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="obtained new client token: AADnuv1cQbLQBG/gOKMObxXcs/gkstAQWpBHD6s1mhCGbQdP7ev7TRZKbQ6/vKrhhId601hfBOF5CPbSVRHWEdYhMvPHjlm4iJO0PhhR5JO0ACZU1tGx4S/mSZ02Kv5Pqzf0XVjm7ZIUR+tvKWdM0VRXDgqBlR963r2YAujtoTSYOnZ60Kromy+EESSUqrj4BywMKfW7qo43JZT3TvJSCCZEd1Grfd21WslqtIgOXbZWQbyNHpA3b7F3yoY=" Aug 17 17:17:18 volumio go-librespot[7202]: time="2024-08-17T17:17:18+02:00" level=debug msg="completed keyexchange" Aug 17 17:17:19 volumio go-librespot[7202]: time="2024-08-17T17:17:19+02:00" level=debug msg="completed challenge" Aug 17 17:17:19 volumio volumio[2538]: info: Getting Spotify volume Aug 17 17:17:19 volumio volumio[2538]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 17 17:17:19 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:19 volumio go-librespot[7202]: time="2024-08-17T17:17:19+02:00" level=debug msg="authenticated as tom7182" Aug 17 17:17:19 volumio volumio[2538]: info: Collecting websocket_client (from camilladsp==1.0.0) Aug 17 17:17:19 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) Aug 17 17:17:19 volumio go-librespot[7202]: time="2024-08-17T17:17:19+02:00" level=debug msg="authenticated as tom7182" Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 17:17:20 volumio volumio[2538]: info: Received Get System Info Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 17:17:20 volumio volumio[2538]: info: Discovery: Getting this device information Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 17:17:20 volumio go-librespot[7202]: time="2024-08-17T17:17:20+02:00" level=debug msg="dealer connection opened" Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 17:17:20 volumio volumio[2538]: info: Received Get System Info Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 17:17:20 volumio volumio[2538]: info: Discovery: Getting this device information Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:20 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 17:17:21 volumio volumio[2538]: info: go-librespot daemon successfully initialized Aug 17 17:17:21 volumio volumio[2538]: info: Initializing connection to go-librespot Websocket Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="new websocket client" Aug 17 17:17:21 volumio volumio[2538]: info: Connection to go-librespot Websocket established Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="stored credentials for tom7182" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="initializing zeroconf session, username: tom7182" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="autoplay enabled: false" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Aug 17 17:17:21 volumio go-librespot[7202]: time="2024-08-17T17:17:21+02:00" level=debug msg="received connection id: OWQ3ODJjMDUtMDZlYS00M2E5LTk4NGUtMmNkMDhmOThlMGFjK2RlYWxlcit0Y3A6Ly8wYWNhNDE5MS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQTQ2OTQxRjNCQzFCMURBMkY2QkVEODk2MDQwODRBRERCMUIzMUQ2RUEzOUNEQTlGRjRGRkExNzdEMDYwMkE4OQ==" Aug 17 17:17:21 volumio volumio[2538]: info: Spotify volume: 100 Aug 17 17:17:21 volumio volumio[2538]: info: Building wheels for collected packages: camilladsp, PyYAML Aug 17 17:17:21 volumio volumio[2538]: info: Running setup.py bdist_wheel for camilladsp: started Aug 17 17:17:22 volumio go-librespot[7202]: time="2024-08-17T17:17:22+02:00" level=debug msg="put connect state because NEW_DEVICE" Aug 17 17:17:22 volumio volumio[2538]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Aug 17 17:17:22 volumio volumio[2538]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-_rsurhlw/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Aug 17 17:17:22 volumio volumio[2538]: info: Running setup.py bdist_wheel for PyYAML: started Aug 17 17:17:24 volumio volumio[2538]: info: Initializing connection to go-librespot Websocket Aug 17 17:17:24 volumio go-librespot[7202]: time="2024-08-17T17:17:24+02:00" level=debug msg="new websocket client" Aug 17 17:17:24 volumio volumio[2538]: info: Connection to go-librespot Websocket established Aug 17 17:17:24 volumio volumio[2538]: info: Getting Spotify volume Aug 17 17:17:24 volumio volumio[2538]: info: Spotify volume: 100 Aug 17 17:17:24 volumio volumio[2538]: 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 17 17:17:24 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:24 volumio volumio[2538]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Aug 17 17:17:24 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Aug 17 17:17:24 volumio volumio[2538]: info: Creating Spotify config file Aug 17 17:17:24 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:24 volumio volumio[2538]: info: Spotify config file written Aug 17 17:17:24 volumio sudo[7229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 17 17:17:24 volumio sudo[7229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:24 volumio systemd[1]: Stopping go-librespot Daemon... Aug 17 17:17:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 17 17:17:24 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 17 17:17:24 volumio systemd[1]: Stopped go-librespot Daemon. Aug 17 17:17:24 volumio volumio[2538]: info: Connection to go-librespot Websocket closed Aug 17 17:17:24 volumio volumio[2538]: info: Connection to go-librespot Websocket closed Aug 17 17:17:24 volumio systemd[1]: Started go-librespot Daemon. Aug 17 17:17:24 volumio sudo[7229]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:24 volumio go-librespot[7231]: Librespot-go daemon starting... Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=info msg="generated new device id: 9d2c26e59c47a9551c3b82962120ce380693c3f3" Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="stored credentials found for tom7182" Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="zeroconf server listening on port 45989" Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="obtained new client token: AACP8sqmpkZ27t0dDGgx3PVydS8gVBsnwsOoewkTAgaqkEv5XR/y7tcQ/zowAyd5JRwp4dTPhs7LMdANXU30X4vfNZwk6uWGCTLmlxJZNhlPgaZLhezFMAW+GfZn0ng8o/+XDop6MVRLJBJiltMx1w7THe34FpYEPu++epS051pRxPcDTvG26+7/Ati6NHuW6we9lHp+V95wQuX0/Nvc3cT0/esQ+P8eKYAK6/uSzBd9SYPAwrTsS5k6P8w=" Aug 17 17:17:24 volumio volumio[2538]: info: Running setup.py bdist_wheel for PyYAML: finished with status 'done' Aug 17 17:17:24 volumio volumio[2538]: info: Stored in directory: /root/.cache/pip/wheels/e6/c6/ef/4e8ee93f1b79fc90562f1600d47189799f8213023d9dadafa2 Aug 17 17:17:24 volumio go-librespot[7231]: time="2024-08-17T17:17:24+02:00" level=debug msg="completed keyexchange" Aug 17 17:17:24 volumio volumio[2538]: info: Successfully built camilladsp PyYAML Aug 17 17:17:25 volumio volumio[2538]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="completed challenge" Aug 17 17:17:25 volumio volumio[2538]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="authenticated as tom7182" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="authenticated as tom7182" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="dealer connection opened" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="initializing zeroconf session, username: tom7182" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="autoplay enabled: false" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="received connection id: MDNlNjUxOWEtMWYwYy00NDBmLTg3ZDAtMDM0MjI4YzgwNGY3K2RlYWxlcit0Y3A6Ly8wYWNhNDEzZS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArOTg5RDhBNjZFQzVBMDdBRDkwMUMwQTQ4QjVCQTVENjA2ODhEMUNBRDQ4QUEwREY0NzQzOUMyMkUzMjZGNTlCOA==" Aug 17 17:17:25 volumio go-librespot[7231]: time="2024-08-17T17:17:25+02:00" level=debug msg="put connect state because NEW_DEVICE" Aug 17 17:17:26 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:27 volumio volumio[2538]: info: Getting Spotify volume Aug 17 17:17:27 volumio volumio[2538]: info: Spotify volume: 100 Aug 17 17:17:27 volumio volumio[2538]: 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 17 17:17:27 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:27 volumio volumio[2538]: info: Initializing connection to go-librespot Websocket Aug 17 17:17:27 volumio go-librespot[7231]: time="2024-08-17T17:17:27+02:00" level=debug msg="new websocket client" Aug 17 17:17:27 volumio volumio[2538]: info: Connection to go-librespot Websocket established Aug 17 17:17:27 volumio volumio[2538]: info: go-librespot daemon successfully initialized Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:28 volumio volumio[2538]: info: Retrieving Cloud Streaming UI Aug 17 17:17:28 volumio volumio[2538]: info: Getting Tidal Cloud Configuration Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:28 volumio volumio[2538]: info: Getting Qobuz Cloud Configuration Aug 17 17:17:28 volumio volumio[2538]: info: Asking plugin for UI Config Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:28 volumio volumio[2538]: info: Getting Spotify Cloud Configuration Aug 17 17:17:28 volumio volumio[2538]: info: Asking plugin for UI Config Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:28 volumio volumio[2538]: info: Saving Spotify Acccount Aug 17 17:17:28 volumio volumio[2538]: info: Got Tidal Cloud Configuration Aug 17 17:17:28 volumio volumio[2538]: info: Got it Aug 17 17:17:28 volumio volumio[2538]: info: Got it Aug 17 17:17:28 volumio volumio[2538]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:28 volumio volumio[2538]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 17:17:28 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 17 17:17:29 volumio volumio[2538]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Aug 17 17:17:29 volumio volumio[2538]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-pprhxvni Aug 17 17:17:30 volumio volumio[2538]: info: Getting Spotify volume Aug 17 17:17:30 volumio volumio[2538]: info: Spotify volume: 100 Aug 17 17:17:30 volumio volumio[2538]: info: Initializing connection to go-librespot Websocket Aug 17 17:17:30 volumio volumio[2538]: 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 17 17:17:30 volumio go-librespot[7231]: time="2024-08-17T17:17:30+02:00" level=debug msg="new websocket client" Aug 17 17:17:30 volumio volumio[2538]: info: Connection to go-librespot Websocket established Aug 17 17:17:30 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:31 volumio volumio[2538]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Aug 17 17:17:31 volumio volumio[2538]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Aug 17 17:17:32 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Aug 17 17:17:32 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:32 volumio volumio[2538]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Aug 17 17:17:32 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) Aug 17 17:17:32 volumio volumio[2538]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Aug 17 17:17:32 volumio volumio[2538]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Aug 17 17:17:33 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Aug 17 17:17:33 volumio volumio[2538]: info: Getting Spotify volume Aug 17 17:17:33 volumio volumio[2538]: info: Spotify volume: 100 Aug 17 17:17:33 volumio volumio[2538]: 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 17 17:17:33 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:33 volumio volumio[2538]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Aug 17 17:17:33 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Aug 17 17:17:33 volumio volumio[2538]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Aug 17 17:17:33 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Aug 17 17:17:34 volumio volumio[2538]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Aug 17 17:17:34 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Aug 17 17:17:34 volumio volumio[2538]: info: Collecting zipp>=3.1.0; python_version < "3.10" (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) Aug 17 17:17:35 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 17:17:35 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 17:17:35 volumio volumio[2538]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Aug 17 17:17:35 volumio volumio[2538]: info: Building wheels for collected packages: camilladsp-plot Aug 17 17:17:35 volumio volumio[2538]: info: Running setup.py bdist_wheel for camilladsp-plot: started Aug 17 17:17:36 volumio volumio[2538]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Aug 17 17:17:36 volumio volumio[2538]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-hkj_l2s5/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Aug 17 17:17:36 volumio volumio[2538]: info: Successfully built camilladsp-plot Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 17 17:17:36 volumio volumio[2538]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 17 17:17:36 volumio volumio[2538]: info: Received Get System Version Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 17:17:36 volumio volumio[2538]: info: Received Get System Info Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 17:17:36 volumio volumio[2538]: info: Discovery: Getting this device information Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:36 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 17:17:36 volumio volumio[2538]: info: Installing collected packages: pyrsistent, zipp, importlib-resources, typing-extensions, pkgutil-resolve-name, importlib-metadata, jsonschema, camilladsp-plot Aug 17 17:17:37 volumio volumio[2538]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Aug 17 17:17:37 volumio volumio[2538]: info: copying hw detection script Aug 17 17:17:37 volumio volumio[2538]: info: Detected cpu architecture as aarch64 Aug 17 17:17:38 volumio volumio[2538]: info: Enabling plugin fusiondsp Aug 17 17:17:38 volumio volumio[2538]: info: Loading plugin "fusiondsp"... Aug 17 17:17:39 volumio volumio[2538]: info: Preparing to generate the ALSA configuration file Aug 17 17:17:39 volumio volumio[2538]: info: Asound.conf file unchanged, so no further update is needed Aug 17 17:17:39 volumio volumio[2538]: info: Output device has changed, restarting MPD Aug 17 17:17:39 volumio sudo[7285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 17:17:39 volumio sudo[7285]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio volumio[2538]: info: Output device has changed, restarting Shairport Sync Aug 17 17:17:39 volumio sudo[7285]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:39 volumio sudo[7288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 17:17:39 volumio sudo[7288]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio systemd[1]: Stopping Music Player Daemon... Aug 17 17:17:39 volumio kernel: asoc-aml-card odroid_hdmi: S/PDIF Playback disable Aug 17 17:17:39 volumio kernel: spdif_a keep clk continuous Aug 17 17:17:39 volumio kernel: aml_spdif_close Aug 17 17:17:39 volumio kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Aug 17 17:17:39 volumio volumio[2538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 17:17:39 volumio volumio[2538]: info: PLUGIN START: fusiondsp Aug 17 17:17:39 volumio volumio[2538]: info: Loading i18n strings for locale fr Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: FusionDsp - mixtype--------------------- Hardware Aug 17 17:17:39 volumio volumio[2538]: info: Preparing to generate the ALSA configuration file Aug 17 17:17:39 volumio volumio[2538]: info: Done. Aug 17 17:17:39 volumio volumio[2538]: info: MPD Permissions set Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio systemd[1]: mpd.service: Succeeded. Aug 17 17:17:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 17 17:17:39 volumio systemd[1]: Starting Music Player Daemon... Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: Asound.conf file unchanged, so no further update is needed Aug 17 17:17:39 volumio volumio[2538]: info: Output device has changed, restarting MPD Aug 17 17:17:39 volumio sudo[7308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 17:17:39 volumio volumio[2538]: info: Output device has changed, restarting Shairport Sync Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:39 volumio sudo[7308]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio sudo[7308]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:39 volumio sudo[7310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 17:17:39 volumio sudo[7310]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio sudo[7297]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 17:17:39 volumio sudo[7297]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio sudo[7297]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:39 volumio volumio[2538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 17:17:39 volumio volumio[2538]: info: MPD Permissions set Aug 17 17:17:39 volumio volumio[2538]: info: Starting Shairport Sync Aug 17 17:17:39 volumio systemd[1]: mpd.service: Succeeded. Aug 17 17:17:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio systemd[1]: Starting Music Player Daemon... Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio sudo[7319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 17:17:39 volumio sudo[7319]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 17:17:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 17:17:39 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 17:17:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:39 volumio sudo[7319]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:39 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:39 volumio volumio[2538]: info: Shairport-Sync Started Aug 17 17:17:39 volumio volumio[2538]: info: Starting Shairport Sync Aug 17 17:17:39 volumio sudo[7320]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 17:17:39 volumio sudo[7320]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio sudo[7320]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:39 volumio sudo[7331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 17:17:39 volumio sudo[7331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 17:17:39 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 17:17:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:39 volumio sudo[7331]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:39 volumio volumio[2538]: info: Shairport-Sync Started Aug 17 17:17:39 volumio volumio[2538]: info: camilladsp Aug 17 17:17:40 volumio sudo[7352]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Aug 17 17:17:40 volumio sudo[7352]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:40 volumio sudo[7352]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:40 volumio sudo[7354]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Aug 17 17:17:40 volumio sudo[7354]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:40 volumio sudo[7354]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:40 volumio sudo[7356]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Aug 17 17:17:40 volumio sudo[7356]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:40 volumio volumio[2538]: info: Reading package lists... Aug 17 17:17:40 volumio volumio[2538]: info: Building dependency tree... Aug 17 17:17:40 volumio volumio[2538]: info: Reading state information... Aug 17 17:17:40 volumio volumio[2538]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 17:17:40 volumio mpd[7335]: Aug 17 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 17 17:17:40 volumio systemd[1]: Started Music Player Daemon. Aug 17 17:17:40 volumio sudo[7288]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:40 volumio sudo[7310]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:41 volumio volumio[2538]: error: MPD error: The expression evaluated to a falsy value: Aug 17 17:17:41 volumio volumio[2538]: assert.ok(self.idling) Aug 17 17:17:41 volumio volumio[2538]: error: The expression evaluated to a falsy value: Aug 17 17:17:41 volumio volumio[2538]: assert.ok(self.idling) Aug 17 17:17:41 volumio volumio[2538]: error: updateQueue error: null Aug 17 17:17:41 volumio volumio[2538]: info: camilladsp spawned new process with pid 7368, instance 1, run: true Aug 17 17:17:41 volumio volumio[2538]: info: camilladsp service started and running in background, instance 1 Aug 17 17:17:41 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:41 volumio kernel: aml_spdif_open Aug 17 17:17:41 volumio kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Aug 17 17:17:41 volumio kernel: spdif_a keep clk continuous Aug 17 17:17:41 volumio kernel: aml_spdif_close Aug 17 17:17:41 volumio kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Aug 17 17:17:41 volumio volumio[2538]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Aug 17 17:17:41 volumio volumio[2538]: info: FusionDsp loaded Aug 17 17:17:41 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 17:17:41 volumio sudo[7376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 17 17:17:41 volumio sudo[7376]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:41 volumio volumio[2538]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 17 17:17:41 volumio volumio[2538]: info: Adding Signal Path Element [object Object] Aug 17 17:17:41 volumio volumio[2538]: info: Adding fusiondspeq DSP Signal Path Element Aug 17 17:17:41 volumio volumio[2538]: info: FusionDsp - ---- installed callbackRead Aug 17 17:17:41 volumio volumio[2538]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 17 17:17:41 volumio systemd[1]: Started FusionDsp Daemon. Aug 17 17:17:41 volumio sudo[7376]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:41 volumio volumio[2538]: 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 17 17:17:41 volumio volumio[2538]: error: FusionDsp - WebSocket error: [object Object] Aug 17 17:17:41 volumio volumio[2538]: info: The following NEW packages will be installed: Aug 17 17:17:41 volumio volumio[2538]: info: drc Aug 17 17:17:42 volumio volumio[2538]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Aug 17 17:17:42 volumio volumio[2538]: info: Need to get 118 kB of archives. Aug 17 17:17:42 volumio volumio[2538]: info: After this operation, 471 kB of additional disk space will be used. Aug 17 17:17:42 volumio volumio[2538]: info: Get:1 http://deb.debian.org/debian buster/main armhf drc armhf 3.2.2~dfsg0-2 [118 kB] Aug 17 17:17:42 volumio volumio[2538]: 0+0 records in Aug 17 17:17:42 volumio volumio[2538]: 0+0 records out Aug 17 17:17:42 volumio volumio[2538]: 0 bytes copied, 0.000133131 s, 0.0 kB/s Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7383, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7390, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7397, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7406, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7413, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7420, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7428, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7435, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7442, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7449, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7456, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7463, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: camilladsp spawned new process with pid 7471, instance 1, run: true Aug 17 17:17:42 volumio volumio[2538]: info: Fetched 118 kB in 0s (711 kB/s) Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7479, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7491, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7498, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7505, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7512, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7519, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7526, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7533, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7540, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: Selecting previously unselected package drc. Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7547, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: [620B blob data] Aug 17 17:17:43 volumio volumio[2538]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7554, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7561, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7568, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7575, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7582, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7589, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7596, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7603, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: Unpacking drc (3.2.2~dfsg0-2) ... Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7613, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7620, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7627, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7634, instance 1, run: true Aug 17 17:17:43 volumio volumio[2538]: info: camilladsp spawned new process with pid 7641, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7648, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7655, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7662, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7669, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7676, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7683, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7690, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7697, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7704, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7711, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7718, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7725, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7732, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7739, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7746, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7753, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7760, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7767, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7774, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7781, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7788, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7795, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7802, instance 1, run: true Aug 17 17:17:44 volumio volumio[2538]: info: camilladsp spawned new process with pid 7809, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7817, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7824, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7831, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7838, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7845, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7852, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7859, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7866, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7873, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7880, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7889, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: Setting up drc (3.2.2~dfsg0-2) ... Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7896, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7903, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7910, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7917, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7924, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7931, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7938, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7945, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7952, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7959, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7966, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7973, instance 1, run: true Aug 17 17:17:45 volumio volumio[2538]: info: camilladsp spawned new process with pid 7980, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 7987, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 7994, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8001, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8008, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8015, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8022, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8029, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8036, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8043, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8050, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8057, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8064, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8071, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8078, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8085, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8092, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8100, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8108, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8115, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8123, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8130, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8137, instance 1, run: true Aug 17 17:17:46 volumio volumio[2538]: info: camilladsp spawned new process with pid 8144, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8151, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8164, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8173, instance 1, run: true Aug 17 17:17:47 volumio sudo[7356]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:47 volumio sudo[6027]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8181, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: Install script completed Aug 17 17:17:47 volumio volumio[2538]: info: Adding reference to registry Aug 17 17:17:47 volumio volumio[2538]: info: Done installing plugin. Aug 17 17:17:47 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 17:17:47 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 17:17:47 volumio volumio[2538]: info: Folder /tmp/plugins removed Aug 17 17:17:47 volumio volumio[2538]: info: Folder /tmp/downloaded_plugin.zip removed Aug 17 17:17:47 volumio volumio[2538]: Plugin install end detected on script Aug 17 17:17:47 volumio volumio[2538]: info: Folder /data/temp removed Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8188, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8195, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8202, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8209, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8216, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8223, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8240, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8265, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8293, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8304, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8311, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8318, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8325, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8332, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8339, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8346, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8353, instance 1, run: true Aug 17 17:17:47 volumio volumio[2538]: info: camilladsp spawned new process with pid 8360, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8367, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8374, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8381, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8388, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8395, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8402, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8409, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8416, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8423, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8430, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8437, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8444, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8452, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8459, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8466, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8473, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8480, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8487, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8494, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8501, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8508, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8515, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8522, instance 1, run: true Aug 17 17:17:48 volumio volumio[2538]: info: camilladsp spawned new process with pid 8529, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8536, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8543, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8550, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8557, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8564, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8571, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: CoreCommandRouter::volumioGetState Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8578, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8585, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8592, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8599, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8606, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8613, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8620, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8627, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8634, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8641, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8648, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8655, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8662, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8669, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8676, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8683, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8690, instance 1, run: true Aug 17 17:17:49 volumio volumio[2538]: info: camilladsp spawned new process with pid 8697, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8704, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8711, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8718, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8725, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8732, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8739, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8746, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8753, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8760, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8767, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8774, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8781, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8788, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8795, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8802, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8809, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8816, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8823, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8830, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8837, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8844, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8851, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8858, instance 1, run: true Aug 17 17:17:50 volumio volumio[2538]: info: camilladsp spawned new process with pid 8865, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8872, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8879, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8886, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8893, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8900, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8907, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8914, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8921, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8928, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8935, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8942, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8949, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8956, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8963, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8970, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8977, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 17 17:17:51 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8984, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8991, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 8998, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 9005, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 9012, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 9019, instance 1, run: true Aug 17 17:17:51 volumio volumio[2538]: info: camilladsp spawned new process with pid 9026, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9033, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9040, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9047, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9054, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9061, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9068, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9075, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9082, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9089, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9096, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9103, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9110, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9117, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9124, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9131, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9138, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9145, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9152, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9159, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9166, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9173, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9180, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9187, instance 1, run: true Aug 17 17:17:52 volumio volumio[2538]: info: camilladsp spawned new process with pid 9194, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9201, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9208, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9215, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9222, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9229, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9236, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9243, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9250, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9257, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9264, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9271, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9278, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9285, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9292, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9299, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9306, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9313, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9320, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9327, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9334, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9341, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9348, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9355, instance 1, run: true Aug 17 17:17:53 volumio volumio[2538]: info: camilladsp spawned new process with pid 9362, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9369, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9376, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9383, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9390, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9397, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9404, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9411, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9418, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9425, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9432, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9439, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9446, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9453, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9460, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9467, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9474, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9481, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9488, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9495, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9502, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9509, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9516, instance 1, run: true Aug 17 17:17:54 volumio volumio[2538]: info: camilladsp spawned new process with pid 9523, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9530, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9537, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9544, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9551, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9558, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9565, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9572, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9579, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9586, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9593, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9600, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9607, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9614, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9621, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9628, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9635, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9642, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9649, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9656, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9663, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: Enabling plugin fusiondsp Aug 17 17:17:55 volumio volumio[2538]: info: Loading plugin "fusiondsp"... Aug 17 17:17:55 volumio volumio[2538]: info: Applying required configuration parameters for plugin fusiondsp Aug 17 17:17:55 volumio volumio[2538]: info: Preparing to generate the ALSA configuration file Aug 17 17:17:55 volumio volumio[2538]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 17 17:17:55 volumio volumio[2538]: info: Reading ALSA contributions from plugins. Aug 17 17:17:55 volumio volumio[2538]: info: camilladsp spawned new process with pid 9670, instance 1, run: true Aug 17 17:17:55 volumio volumio[2538]: info: Asound.conf file written Aug 17 17:17:55 volumio kernel: aml_spdif_open Aug 17 17:17:55 volumio kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Aug 17 17:17:55 volumio kernel: set normal 512 fs /4 fs Aug 17 17:17:55 volumio kernel: set spdifout clk:5644800, mpll:22579200 Aug 17 17:17:55 volumio kernel: get spdifout clk:5644797, mpll:22579186 Aug 17 17:17:55 volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Aug 17 17:17:55 volumio kernel: set normal 512 fs /4 fs Aug 17 17:17:55 volumio kernel: set spdifout clk:5644800, mpll:22579200 Aug 17 17:17:55 volumio kernel: get spdifout clk:5644797, mpll:22579186 Aug 17 17:17:55 volumio kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 17 17:17:55 volumio kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Aug 17 17:17:55 volumio sudo[9678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 17 17:17:55 volumio sudo[9678]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:55 volumio sudo[9678]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:55 volumio volumio[2538]: No state is present for card ODROIDHDMI Aug 17 17:17:55 volumio volumio[2538]: Found hardware: "ODROID-HDMI" "" "" "" "" Aug 17 17:17:55 volumio volumio[2538]: Hardware is initialized using a generic method Aug 17 17:17:55 volumio volumio[2538]: No state is present for card ODROIDHDMI Aug 17 17:17:55 volumio volumio[2538]: info: Output device has changed, restarting MPD Aug 17 17:17:55 volumio sudo[9684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 17:17:55 volumio sudo[9684]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:55 volumio volumio[2538]: info: Output device has changed, restarting Shairport Sync Aug 17 17:17:55 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:55 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:55 volumio sudo[9684]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:55 volumio sudo[9687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 17:17:55 volumio sudo[9687]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:55 volumio systemd[1]: Stopping Music Player Daemon... Aug 17 17:17:56 volumio volumio[2538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 17:17:56 volumio volumio[2538]: info: PLUGIN START: fusiondsp Aug 17 17:17:56 volumio volumio[2538]: info: Loading i18n strings for locale fr Aug 17 17:17:56 volumio systemd[1]: mpd.service: Succeeded. Aug 17 17:17:56 volumio systemd[1]: Stopped Music Player Daemon. Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: FusionDsp - mixtype--------------------- Hardware Aug 17 17:17:56 volumio volumio[2538]: info: Preparing to generate the ALSA configuration file Aug 17 17:17:56 volumio systemd[1]: Starting Music Player Daemon... Aug 17 17:17:56 volumio volumio[2538]: info: Done. Aug 17 17:17:56 volumio volumio[2538]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 17 17:17:56 volumio volumio[2538]: info: Reading ALSA contributions from plugins. Aug 17 17:17:56 volumio volumio[2538]: info: MPD Permissions set Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: Starting Shairport Sync Aug 17 17:17:56 volumio volumio[2538]: info: Asound.conf file unchanged, so no further update is needed Aug 17 17:17:56 volumio volumio[2538]: info: Output device has changed, restarting MPD Aug 17 17:17:56 volumio sudo[9693]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 17:17:56 volumio sudo[9693]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:56 volumio sudo[9693]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:56 volumio sudo[9706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 17:17:56 volumio sudo[9706]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:56 volumio sudo[9711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 17:17:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 17:17:56 volumio sudo[9711]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:56 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 17:17:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:56 volumio volumio[2538]: info: Output device has changed, restarting Shairport Sync Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:56 volumio sudo[9711]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:56 volumio sudo[9706]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:56 volumio sudo[9718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 17:17:56 volumio sudo[9718]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:56 volumio volumio[2538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 17:17:56 volumio volumio[2538]: info: MPD Permissions set Aug 17 17:17:56 volumio volumio[2538]: info: Shairport-Sync Started Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 17 17:17:56 volumio systemd[1]: mpd.service: Succeeded. Aug 17 17:17:56 volumio systemd[1]: Stopped Music Player Daemon. Aug 17 17:17:56 volumio systemd[1]: Starting Music Player Daemon... Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 17:17:56 volumio volumio[2538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 17:17:56 volumio volumio[2538]: info: Starting Shairport Sync Aug 17 17:17:56 volumio sudo[9733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 17:17:56 volumio sudo[9733]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 17:17:56 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 17:17:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 17:17:56 volumio sudo[9726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 17 17:17:56 volumio sudo[9726]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 17:17:56 volumio sudo[9733]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:56 volumio volumio[2538]: info: Shairport-Sync Started Aug 17 17:17:56 volumio sudo[9726]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:57 volumio mpd[9741]: Aug 17 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 17 17:17:57 volumio systemd[1]: Started Music Player Daemon. Aug 17 17:17:57 volumio sudo[9718]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:57 volumio sudo[9687]: pam_unix(sudo:session): session closed for user root Aug 17 17:17:57 volumio volumio[2538]: error: MPD error: The expression evaluated to a falsy value: Aug 17 17:17:57 volumio volumio[2538]: assert.ok(self.idling) Aug 17 17:17:57 volumio volumio[2538]: error: The expression evaluated to a falsy value: Aug 17 17:17:57 volumio volumio[2538]: assert.ok(self.idling) Aug 17 17:17:57 volumio volumio[2538]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 17:17:57 volumio volumio[2538]: TypeError: Cannot read property 'off' of undefined Aug 17 17:17:57 volumio volumio[2538]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Aug 17 17:17:57 volumio volumio[2538]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 17 17:17:57 volumio volumio[2538]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 17 17:17:57 volumio volumio[2538]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Aug 17 17:17:57 volumio volumio[2538]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1380:47) Aug 17 17:17:57 volumio volumio[2538]: at Socket.emit (events.js:400:28) Aug 17 17:17:57 volumio volumio[2538]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 17 17:17:57 volumio volumio[2538]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 17 17:17:57 volumio volumio[2538]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 17:17:58 volumio sudo[9753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 17:16 Aug 17 17:17:58 volumio sudo[9753]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="6e682b9410d28f3874f90b2d7789db824a859264" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7c81f20187867c3bd55475ead0f12800fe93082c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 08 Jun 2024 11:49:15 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="odroidc4" VOLUMIO_DEVICENAME="Odroid-C4" VOLUMIO_HASH="d2400f5f761721872d49eaefed0badd6"