-- Logs begin at Tue 2025-05-13 12:44:19 EDT, end at Thu 2025-06-05 14:26:39 EDT. -- Jun 05 14:25:00 integro volumio[3175]: info: Setting up gir1.2-glib-2.0:armhf (1.58.3-2) ... Jun 05 14:25:00 integro volumio[3175]: info: Setting up python3-pip (18.1-5) ... Jun 05 14:25:00 integro volumio[3175]: info: Setting up libgcc-8-dev:armhf (8.3.0-6) ... Jun 05 14:25:00 integro volumio[3175]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Jun 05 14:25:02 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:25:04 integro volumio[3175]: info: Setting up libc6-dev:armhf (2.28-10+deb10u1) ... Jun 05 14:25:04 integro volumio[3175]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6) ... Jun 05 14:25:04 integro volumio[3175]: info: Setting up gcc-8 (8.3.0-6) ... Jun 05 14:25:04 integro volumio[3175]: info: Setting up gcc (4:8.3.0-1) ... Jun 05 14:25:04 integro volumio[3175]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Jun 05 14:25:04 integro volumio[3175]: info: Setting up python3-secretstorage (2.3.1-2) ... Jun 05 14:25:04 integro volumio[3175]: info: Setting up python3-gi (3.30.4-1) ... Jun 05 14:25:05 integro volumio[3175]: Cannot compose Albumart path Jun 05 14:25:07 integro volumio[3175]: Cannot compose Albumart path Jun 05 14:25:10 integro volumio[3175]: info: sendMpdCommand status took 36971 milliseconds Jun 05 14:25:10 integro volumio[3175]: info: sendMpdCommand status took 36969 milliseconds Jun 05 14:25:10 integro volumio[3175]: info: sendMpdCommand status took 36969 milliseconds Jun 05 14:25:10 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:25:10 integro volumio[3175]: info: Command Router : Notfying DB Updatefalse Jun 05 14:25:10 integro volumio[3175]: info: CoreCommandRouter::Close All Modals sent Jun 05 14:25:10 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:25:10 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:25:10 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:25:10 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:25:10 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:25:10 integro volumio[3175]: info: Setting up g++-8 (8.3.0-6) ... Jun 05 14:25:10 integro volumio[3175]: info: Setting up python3-keyring (17.1.1-1) ... Jun 05 14:25:10 integro volumio[3175]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Jun 05 14:25:10 integro volumio[3175]: info: Setting up python3.7-dev (3.7.3-2+deb10u3) ... Jun 05 14:25:10 integro volumio[3175]: info: Setting up g++ (4:8.3.0-1) ... Jun 05 14:25:10 integro volumio[3175]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Jun 05 14:25:10 integro volumio[3175]: info: Setting up build-essential (12.6) ... Jun 05 14:25:10 integro volumio[3175]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Jun 05 14:25:10 integro volumio[3175]: info: Setting up python3-dev (3.7.3-1) ... Jun 05 14:25:10 integro volumio[3175]: info: Processing triggers for man-db (2.8.5-2) ... Jun 05 14:25:10 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:25:21 integro volumio[3175]: info: Processing triggers for libc-bin (2.28-10+deb10u1) ... Jun 05 14:25:23 integro sudo[26439]: pam_unix(sudo:session): session closed for user root Jun 05 14:25:23 integro sudo[32038]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jun 05 14:25:23 integro sudo[32038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:25:23 integro sudo[32038]: pam_unix(sudo:session): session closed for user root Jun 05 14:25:25 integro volumio[3175]: info: lib/systemd/system/fusiondsp.service Jun 05 14:25:36 integro volumio[3175]: Cannot compose Albumart path Jun 05 14:25:37 integro volumio[3175]: Cannot compose Albumart path Jun 05 14:25:40 integro volumio[3175]: info: sendMpdCommand status took 67063 milliseconds Jun 05 14:25:40 integro volumio[3175]: info: sendMpdCommand status took 67071 milliseconds Jun 05 14:25:40 integro volumio[3175]: info: sendMpdCommand status took 67071 milliseconds Jun 05 14:25:40 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:25:40 integro volumio[3175]: info: Command Router : Notfying DB Updatefalse Jun 05 14:25:40 integro volumio[3175]: info: CoreCommandRouter::Close All Modals sent Jun 05 14:25:40 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:25:40 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:25:40 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:25:40 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:25:41 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:25:41 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:25:42 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Jun 05 14:25:43 integro volumio[3175]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 05 14:25:43 integro volumio[3175]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 05 14:25:44 integro sudo[32102]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jun 05 14:25:44 integro sudo[32102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:25:45 integro sudo[32102]: pam_unix(sudo:session): session closed for user root Jun 05 14:25:45 integro sudo[32104]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jun 05 14:25:45 integro sudo[32104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:25:45 integro sudo[32104]: pam_unix(sudo:session): session closed for user root Jun 05 14:25:45 integro sudo[32106]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jun 05 14:25:45 integro sudo[32106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:25:45 integro sudo[32106]: pam_unix(sudo:session): session closed for user root Jun 05 14:25:45 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Jun 05 14:25:45 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:25:47 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:25:47 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:25:47 integro volumio[3175]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jun 05 14:25:47 integro volumio[3175]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jun 05 14:25:47 integro volumio[3175]: info: Jun 05 14:25:47 integro volumio[3175]: info: cgui-1.0.0.zip opened Jun 05 14:25:47 integro volumio[3175]: info: creating directory: cgui/ Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/LICENSE.txt Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/README.md Jun 05 14:25:47 integro volumio[3175]: info: creating directory: cgui/build/ Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/robots.txt Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/logo512.png Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/asset-manifest.json Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/index.html Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/.put_statics_here Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/favicon.ico Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/logo192.png Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/manifest.json Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/css-variables.css Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/service-worker.js Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jun 05 14:25:47 integro volumio[3175]: info: creating directory: cgui/build/static/ Jun 05 14:25:47 integro volumio[3175]: info: creating directory: cgui/build/static/css/ Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jun 05 14:25:47 integro volumio[3175]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jun 05 14:25:48 integro volumio[3175]: info: creating directory: cgui/build/static/media/ Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jun 05 14:25:48 integro volumio[3175]: info: creating directory: cgui/build/static/js/ Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.71e11955.js Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jun 05 14:25:48 integro volumio[3175]: info: creating directory: cgui/config/ Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/config/camillagui.yml Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/config/gui-config.yml Jun 05 14:25:48 integro volumio[3175]: info: creating directory: cgui/backend/ Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/filters_test.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/version.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/filters.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/settings.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/views.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/filterdefaults.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__init__.py Jun 05 14:25:48 integro volumio[3175]: info: creating directory: cgui/backend/__pycache__/ Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/routes.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/backend/filemanagement.py Jun 05 14:25:48 integro volumio[3175]: info: extracting: cgui/main.py Jun 05 14:25:49 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Jun 05 14:25:54 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:25:57 integro volumio[3175]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jun 05 14:25:57 integro volumio[3175]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-2fs_lj2y Jun 05 14:25:58 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Jun 05 14:26:01 integro volumio[3175]: info: Collecting PyYAML (from camilladsp==1.0.0) Jun 05 14:26:01 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:26:01 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 11 Jun 05 14:26:04 integro volumio[3175]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Jun 05 14:26:04 integro volumio[3175]: info: Installing build dependencies: started Jun 05 14:26:04 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:26:09 integro volumio[3175]: Cannot compose Albumart path Jun 05 14:26:10 integro volumio[3175]: Cannot compose Albumart path Jun 05 14:26:13 integro volumio[3175]: info: sendMpdCommand status took 100358 milliseconds Jun 05 14:26:13 integro volumio[3175]: info: sendMpdCommand status took 100359 milliseconds Jun 05 14:26:13 integro volumio[3175]: info: sendMpdCommand status took 100359 milliseconds Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:13 integro volumio[3175]: info: Command Router : Notfying DB Updatefalse Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::Close All Modals sent Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:13 integro volumio[3175]: info: sendMpdCommand playlistinfo took 63633 milliseconds Jun 05 14:26:13 integro volumio[3175]: info: sendMpdCommand playlistinfo took 63632 milliseconds Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:13 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:13 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:13 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:13 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89977,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1604 Kbps","isStreaming":false,"title":"160752515&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/160752515&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:13 integro volumio[3175]: verbose: CURRENT POSITION 2 Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:13 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:13 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:13 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:13 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89977,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1604 Kbps","isStreaming":false,"title":"160752515&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/160752515&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:13 integro volumio[3175]: verbose: CURRENT POSITION 2 Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:13 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:13 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:13 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:13 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:13 integro volumio[3175]: info: ------------------------------ 100958ms Jun 05 14:26:13 integro volumio[3175]: info: ------------------------------ 100963ms Jun 05 14:26:14 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:26:14 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Jun 05 14:26:14 integro volumio[3175]: info: sendMpdCommand playlistinfo took 33325 milliseconds Jun 05 14:26:14 integro volumio[3175]: info: sendMpdCommand playlistinfo took 33324 milliseconds Jun 05 14:26:14 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:14 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:14 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:14 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:14 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91725,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1620 Kbps","isStreaming":false,"title":"160752515&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/160752515&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:14 integro volumio[3175]: verbose: CURRENT POSITION 2 Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:14 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:14 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:14 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91725,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1579 Kbps","isStreaming":false,"title":"160752515&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/160752515&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:14 integro volumio[3175]: verbose: CURRENT POSITION 2 Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:14 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: ------------------------------ 101156ms Jun 05 14:26:14 integro volumio[3175]: info: ------------------------------ 101156ms Jun 05 14:26:14 integro volumio[3175]: info: sendMpdCommand playlistinfo took 345 milliseconds Jun 05 14:26:14 integro volumio[3175]: info: sendMpdCommand playlistinfo took 343 milliseconds Jun 05 14:26:14 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:14 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:14 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:14 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:14 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93348,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1496 Kbps","isStreaming":false,"title":"160752515&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/160752515&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:14 integro volumio[3175]: verbose: CURRENT POSITION 2 Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:14 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:14 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:14 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93348,"duration":200,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1794 Kbps","isStreaming":false,"title":"160752515&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/160752515&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:14 integro volumio[3175]: verbose: CURRENT POSITION 2 Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:14 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:14 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:14 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:14 integro volumio[3175]: info: ------------------------------ 101224ms Jun 05 14:26:14 integro volumio[3175]: info: ------------------------------ 101225ms Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:14 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:26:14 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:26:14 integro volumio[3175]: Upnp client error: Error: This socket has been ended by the other party Jun 05 14:26:14 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.642171, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 05 14:26:14 integro nmbd[3059]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.642518, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 05 14:26:14 integro nmbd[3059]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.642733, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 05 14:26:14 integro nmbd[3059]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.642888, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 05 14:26:14 integro nmbd[3059]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.643033, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 05 14:26:14 integro nmbd[3059]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.643164, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 05 14:26:14 integro nmbd[3059]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.643298, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 05 14:26:14 integro nmbd[3059]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.643423, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 05 14:26:14 integro nmbd[3059]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.643554, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 05 14:26:14 integro nmbd[3059]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 05 14:26:14 integro nmbd[3059]: [2025/06/05 14:26:14.643680, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 05 14:26:14 integro nmbd[3059]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 05 14:26:15 integro nmbd[3059]: [2025/06/05 14:26:15.645841, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 05 14:26:15 integro nmbd[3059]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 05 14:26:16 integro volumio[3175]: verbose: New Socket.io Connection to 192.168.10.72 from 192.168.10.102 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 05 14:26:16 integro volumio[3175]: info: Received Get System Info Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:26:16 integro volumio[3175]: info: Discovery: Getting this device information Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 14:26:16 integro volumio[3175]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 05 14:26:16 integro volumio[3175]: info: Received Get System Info Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 14:26:16 integro volumio[3175]: info: Discovery: Getting this device information Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:16 integro volumio[3175]: info: Listing playlists Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::volumioGetQueue Jun 05 14:26:16 integro volumio[3175]: info: CoreStateMachine::getQueue Jun 05 14:26:16 integro volumio[3175]: info: CorePlayQueue::getQueue Jun 05 14:26:16 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 05 14:26:21 integro volumio[3175]: info: CorePlayQueue::getTrack 2 Jun 05 14:26:21 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:21 integro volumio[3175]: info: Prefetching next song Jun 05 14:26:21 integro volumio[3175]: info: [1749147981390] ControllerTidal::prefetch Jun 05 14:26:21 integro volumio[3175]: info: Getting stream with soundQuality HI_RES Jun 05 14:26:21 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES" Jun 05 14:26:21 integro volumio[3175]: STREAMING PROXY: Handling url /?data=tidal://song/77615080&soundQuality=HI_RES Jun 05 14:26:21 integro volumio[3175]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES" took 2 milliseconds Jun 05 14:26:21 integro volumio[3175]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 05 14:26:21 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 05 14:26:21 integro volumio[3175]: info: Jun 05 14:26:21 integro volumio[3175]: ---------------------------- MPD announces system playlist update Jun 05 14:26:21 integro volumio[3175]: info: Ignoring MPD Status Update Jun 05 14:26:21 integro volumio[3175]: info: sendMpdCommand consume 1 took 6 milliseconds Jun 05 14:26:21 integro volumio[3175]: info: Jun 05 14:26:21 integro volumio[3175]: ---------------------------- MPD announces system playlist update Jun 05 14:26:21 integro volumio[3175]: info: Ignoring MPD Status Update Jun 05 14:26:21 integro volumio[3175]: info: Jun 05 14:26:21 integro volumio[3175]: ---------------------------- MPD announces system playlist update Jun 05 14:26:21 integro volumio[3175]: info: Ignoring MPD Status Update Jun 05 14:26:21 integro volumio[3175]: info: ------------------------------ 14ms Jun 05 14:26:21 integro volumio[3175]: info: ------------------------------ 8ms Jun 05 14:26:21 integro volumio[3175]: info: ------------------------------ 6ms Jun 05 14:26:21 integro volumio[3175]: info: Executing endpoint getStreamUrltidal Jun 05 14:26:21 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 05 14:26:21 integro volumio[3175]: info: getStreamUrl took 298 milliseconds Jun 05 14:26:21 integro volumio[3175]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkZmE0ZmQ3YTFmYTY1NTg4MTVlNDczYTQ3YmNhZGIzMF82Mi5tcDQ/0.flac?token=1749151581~ZjQzMWY3Yzc2YjE4ZWY1OGE1MGVlZGRkMTYyOWI0MjBkZTI5MmEzMw== Jun 05 14:26:21 integro volumio[3175]: STREAMING PROXY: Response: 200, length: 40689373 Jun 05 14:26:23 integro volumio[3175]: STREAMING PROXY: Client dropped request, destroying Jun 05 14:26:24 integro volumio[3175]: info: Jun 05 14:26:24 integro volumio[3175]: ---------------------------- MPD announces system playlist update Jun 05 14:26:24 integro volumio[3175]: info: Ignoring MPD Status Update Jun 05 14:26:24 integro volumio[3175]: info: Jun 05 14:26:24 integro volumio[3175]: ---------------------------- MPD announces state update: player Jun 05 14:26:24 integro volumio[3175]: info: ControllerMpd::getState Jun 05 14:26:24 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand status Jun 05 14:26:24 integro volumio[3175]: info: Jun 05 14:26:24 integro volumio[3175]: ---------------------------- MPD announces system playlist update Jun 05 14:26:24 integro volumio[3175]: info: Ignoring MPD Status Update Jun 05 14:26:24 integro volumio[3175]: info: Jun 05 14:26:24 integro volumio[3175]: ---------------------------- MPD announces state update: player Jun 05 14:26:24 integro volumio[3175]: info: ControllerMpd::getState Jun 05 14:26:24 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand status Jun 05 14:26:24 integro volumio[3175]: info: Jun 05 14:26:24 integro volumio[3175]: ---------------------------- MPD announces system playlist update Jun 05 14:26:24 integro volumio[3175]: info: Ignoring MPD Status Update Jun 05 14:26:24 integro volumio[3175]: info: Jun 05 14:26:24 integro volumio[3175]: ---------------------------- MPD announces state update: player Jun 05 14:26:24 integro volumio[3175]: info: ControllerMpd::getState Jun 05 14:26:24 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand status Jun 05 14:26:24 integro volumio[3175]: info: ------------------------------ 12ms Jun 05 14:26:25 integro volumio[3175]: info: CoreStateMachine::startPlaybackTimer Jun 05 14:26:25 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:25 integro kernel: asoc-aml-card auge_sound: tdm playback stop Jun 05 14:26:25 integro kernel: spdif_a is set to disable Jun 05 14:26:25 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 05 14:26:25 integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jun 05 14:26:25 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jun 05 14:26:25 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 05 14:26:26 integro kernel: aml_tdm_open Jun 05 14:26:26 integro kernel: Not init audio effects Jun 05 14:26:26 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 05 14:26:26 integro kernel: set mclk:24576000, mpll:49152000, get mclk:24575987, mpll:49151974 Jun 05 14:26:26 integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050246618, id(1), clksel(1) Jun 05 14:26:26 integro kernel: aml_dai_set_tdm_fmt(), fmt not change Jun 05 14:26:26 integro kernel: dump_pcm_setting(ffffffc050246618) Jun 05 14:26:26 integro kernel: pcm_mode(1) Jun 05 14:26:26 integro kernel: sysclk(24576000) Jun 05 14:26:26 integro kernel: sysclk_bclk_ratio(4) Jun 05 14:26:26 integro kernel: bclk(6144000) Jun 05 14:26:26 integro kernel: bclk_lrclk_ratio(64) Jun 05 14:26:26 integro kernel: lrclk(96000) Jun 05 14:26:26 integro kernel: tx_mask(0x3) Jun 05 14:26:26 integro kernel: rx_mask(0x3) Jun 05 14:26:26 integro kernel: slots(2) Jun 05 14:26:26 integro kernel: slot_width(32) Jun 05 14:26:26 integro kernel: lane_mask_in(0x2) Jun 05 14:26:26 integro kernel: lane_mask_out(0x1) Jun 05 14:26:26 integro kernel: lane_oe_mask_in(0x0) Jun 05 14:26:26 integro kernel: lane_oe_mask_out(0x0) Jun 05 14:26:26 integro kernel: lane_lb_mask_in(0x0) Jun 05 14:26:26 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jun 05 14:26:26 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jun 05 14:26:26 integro kernel: set mclk:24576000, mpll:49152000, get mclk:24575987, mpll:49151974 Jun 05 14:26:26 integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jun 05 14:26:26 integro kernel: aml_dai_set_bclk_ratio, select I2S mode Jun 05 14:26:26 integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jun 05 14:26:26 integro kernel: aml_tdm_prepare(), reset fddr Jun 05 14:26:26 integro kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Jun 05 14:26:26 integro kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jun 05 14:26:26 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 05 14:26:26 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jun 05 14:26:26 integro kernel: aml_tdm_prepare(), reset fddr Jun 05 14:26:26 integro kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Jun 05 14:26:26 integro kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jun 05 14:26:26 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 05 14:26:26 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand status took 1201 milliseconds Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:26 integro volumio[3175]: info: Jun 05 14:26:26 integro volumio[3175]: ---------------------------- MPD announces state update: player Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::getState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand status Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 1215ms Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand status took 1214 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 1213ms Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand status took 1211 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"77615080&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:26 integro volumio[3175]: verbose: CURRENT POSITION 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:26 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 1259ms Jun 05 14:26:26 integro volumio[3175]: info: Jun 05 14:26:26 integro volumio[3175]: ---------------------------- MPD announces state update: player Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::getState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand status Jun 05 14:26:26 integro volumio[3175]: info: Jun 05 14:26:26 integro volumio[3175]: ---------------------------- MPD announces state update: player Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::getState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand status Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand status took 51 milliseconds Jun 05 14:26:26 integro kernel: asoc-aml-card auge_sound: tdm playback enable Jun 05 14:26:26 integro kernel: spdif_a is set to enable Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand playlistinfo took 47 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand playlistinfo took 46 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand status took 8 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand status took 6 milliseconds Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseState Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":220,"duration":153,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2130 Kbps","isStreaming":false,"title":"77615080&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:26 integro volumio[3175]: verbose: CURRENT POSITION 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:26 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":656,"duration":153,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2130 Kbps","isStreaming":false,"title":"77615080&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:26 integro volumio[3175]: verbose: CURRENT POSITION 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:26 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 1332ms Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 1331ms Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand playlistinfo took 154 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand playlistinfo took 152 milliseconds Jun 05 14:26:26 integro volumio[3175]: info: sendMpdCommand playlistinfo took 152 milliseconds Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:26 integro volumio[3175]: verbose: ControllerMpd::parseTrackInfo Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1139,"duration":153,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2078 Kbps","isStreaming":false,"title":"77615080&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:26 integro volumio[3175]: verbose: CURRENT POSITION 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:26 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1139,"duration":153,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2078 Kbps","isStreaming":false,"title":"77615080&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:26 integro volumio[3175]: verbose: CURRENT POSITION 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:26 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: ControllerMpd::pushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::servicePushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1139,"duration":153,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2078 Kbps","isStreaming":false,"title":"77615080&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615080&soundQuality=HI_RES","trackType":"tidal"} Jun 05 14:26:26 integro volumio[3175]: verbose: CURRENT POSITION 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState stateService play Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::syncState currentStatus play Jun 05 14:26:26 integro volumio[3175]: info: Received an update from plugin. extracting info from payload Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:26 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:26 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:26 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 286ms Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 243ms Jun 05 14:26:26 integro volumio[3175]: info: ------------------------------ 241ms Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:26 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:28 integro volumio[3175]: info: Installing build dependencies: finished with status 'done' Jun 05 14:26:34 integro volumio[3175]: info: Enabling plugin fusiondsp Jun 05 14:26:34 integro volumio[3175]: info: Loading plugin "fusiondsp"... Jun 05 14:26:34 integro volumio[3175]: info: Preparing to generate the ALSA configuration file Jun 05 14:26:35 integro volumio[3175]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 05 14:26:35 integro volumio[3175]: info: Reading ALSA contributions from plugins. Jun 05 14:26:35 integro volumio[3175]: info: Asound.conf file unchanged, so no further update is needed Jun 05 14:26:35 integro volumio[3175]: info: Output device has changed, restarting MPD Jun 05 14:26:35 integro sudo[32322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 14:26:35 integro sudo[32322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:35 integro sudo[32322]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:35 integro volumio[3175]: info: Output device has changed, restarting Shairport Sync Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:26:35 integro sudo[32329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 14:26:35 integro sudo[32329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:35 integro systemd[1]: Stopping Music Player Daemon... Jun 05 14:26:35 integro volumio[3175]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 14:26:35 integro volumio[3175]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro kernel: asoc-aml-card auge_sound: tdm playback stop Jun 05 14:26:35 integro kernel: spdif_a is set to disable Jun 05 14:26:35 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 05 14:26:35 integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jun 05 14:26:35 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jun 05 14:26:35 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 05 14:26:35 integro volumio[3175]: info: QobuzConnect: setDeactiveState invoked Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:35 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:35 integro volumio[3175]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro kernel: aml_tdm_open Jun 05 14:26:35 integro kernel: Not init audio effects Jun 05 14:26:35 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 05 14:26:35 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jun 05 14:26:35 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 05 14:26:35 integro vtcs[3885]: [2025-06-05 14:26:35.290] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jun 05 14:26:35 integro vtcs[3885]: [2025-06-05 14:26:35.266] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jun 05 14:26:35 integro volumio[3175]: info: PLUGIN START: fusiondsp Jun 05 14:26:35 integro volumio[3175]: info: Loading i18n strings for locale en Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 05 14:26:35 integro sudo[32352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 05 14:26:35 integro sudo[32352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: FusionDsp - mixtype--------------------- Hardware Jun 05 14:26:35 integro volumio[3175]: info: Preparing to generate the ALSA configuration file Jun 05 14:26:35 integro volumio[3175]: info: Done. Jun 05 14:26:35 integro systemd[1]: Stopping Volumio Tidal Connect Service... Jun 05 14:26:35 integro systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 05 14:26:35 integro systemd[1]: vtcs.service: Succeeded. Jun 05 14:26:35 integro systemd[1]: Stopped Volumio Tidal Connect Service. Jun 05 14:26:35 integro sudo[32352]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:35 integro sudo[32355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 05 14:26:35 integro sudo[32355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:35 integro sudo[32355]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:35 integro volumio[3175]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 05 14:26:35 integro volumio[3175]: info: Reading ALSA contributions from plugins. Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 14:26:35 integro sudo[32363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 05 14:26:35 integro sudo[32363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:35 integro volumio[3175]: info: MPD Permissions set Jun 05 14:26:35 integro sudo[32363]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:35 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:35 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:35 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:35 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:35 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:35 integro sudo[32365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 05 14:26:35 integro sudo[32365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:35 integro volumio[3175]: info: CoreStateMachine::pushState Jun 05 14:26:35 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::volumioPushState Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:35 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:35 integro volumio[3175]: info: MRS: Pushing multiroomSync output update for this device Jun 05 14:26:35 integro volumio[3175]: info: MRS: Pushing multiroomSync output Jun 05 14:26:35 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:35 integro volumio[3175]: info: Signalling Playback active due to playback status change Jun 05 14:26:35 integro systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 05 14:26:35 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:35 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:35 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:35 integro volumio[3175]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro qobuz-connect[3748]: 20250605 14:26:35.751 [3748.3748] INFO SampleApp: Stopping Local configuration server Jun 05 14:26:35 integro qobuz-connect[3748]: 20250605 14:26:35.762 [3748.3748] INFO SampleApp: shat down connection on UNIX socket Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 05 14:26:35 integro systemd[1]: qobuz-connect.service: Succeeded. Jun 05 14:26:35 integro systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 05 14:26:35 integro systemd[1]: Started Volumio Qobuz Connect Service. Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro sudo[32365]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 14:26:35 integro volumio[3175]: info: Not Reporting Auto name since its the default one Jun 05 14:26:36 integro volumio[3175]: info: FusionDsp - Jun 05 14:26:36 integro systemd[1]: mpd.service: Succeeded. Jun 05 14:26:36 integro systemd[1]: Stopped Music Player Daemon. Jun 05 14:26:36 integro systemd[1]: Starting Music Player Daemon... Jun 05 14:26:36 integro volumio[3175]: info: FusionDsp - Jun 05 14:26:36 integro sudo[32387]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 05 14:26:36 integro sudo[32387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:36 integro sudo[32387]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:36 integro volumio[3175]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 05 14:26:36 integro volumio[3175]: info: QobuzConnect: setDeactiveState invoked Jun 05 14:26:36 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:36 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:36 integro volumio[3175]: info: FusionDsp - undefined Jun 05 14:26:36 integro volumio[3175]: info: FusionDsp - undefined Jun 05 14:26:36 integro volumio[3175]: info: Starting Shairport Sync Jun 05 14:26:36 integro sudo[32393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 14:26:36 integro sudo[32393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:36 integro volumio[3175]: info: Executing endpoint qc_getconfig Jun 05 14:26:36 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 05 14:26:36 integro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 05 14:26:36 integro systemd[1]: shairport-sync.service: Succeeded. Jun 05 14:26:36 integro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.776 [32373.32373] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 05 14:26:36 integro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 14:26:36 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 05 14:26:36 integro sudo[32393]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.823 [32373.32373] INFO VolumeManager: [0xab8fdaf8]: Setting new playback volume: 75 Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.824 [32373.32373] INFO VolumeManager: [0xab8fdaf8]: Setting new mute state: 0 Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.824 [32373.32373] INFO QobuzConnect: [0xab8fe4c8]: Client initialized! Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.824 [32373.32373] INFO SampleApp: Starting Avahi advertising, name: Integro, service name: _qobuz-connect._tcp Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.841 [32373.32373] INFO LocalConfigManager: [0xab8fd3d8]: Starting Local Configuration server Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.842 [32373.32373] INFO SampleApp: Starting Local configuration server Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.855 [32373.32373] INFO SampleApp: Connected to UNIX socket client 0xab8f2ed8 Jun 05 14:26:36 integro qobuz-connect[32373]: 20250605 14:26:36.926 [32373.32373] INFO SampleApp: Playback volume changed: 75 Jun 05 14:26:37 integro volumio[3175]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 05 14:26:37 integro volumio[3175]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 05 14:26:37 integro volumio[3175]: info: Asound.conf file unchanged, so no further update is needed Jun 05 14:26:37 integro volumio[3175]: info: Output device has changed, restarting MPD Jun 05 14:26:37 integro sudo[32405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 05 14:26:37 integro sudo[32405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro sudo[32405]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro sudo[32408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 14:26:37 integro sudo[32408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro volumio[3175]: info: Output device has changed, restarting Shairport Sync Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 14:26:37 integro sudo[32409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 05 14:26:37 integro sudo[32408]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro sudo[32409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro sudo[32412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 14:26:37 integro sudo[32412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro systemd[1]: Stopping RAAT DAEMON... Jun 05 14:26:37 integro systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 05 14:26:37 integro systemd[1]: raat-daemon.service: Succeeded. Jun 05 14:26:37 integro systemd[1]: Stopped RAAT DAEMON. Jun 05 14:26:37 integro systemd[1]: Started RAAT DAEMON. Jun 05 14:26:37 integro sudo[32409]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro volumio[3175]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 14:26:37 integro volumio[3175]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:37 integro systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 05 14:26:37 integro systemd[1]: mpd.service: Succeeded. Jun 05 14:26:37 integro systemd[1]: Stopped Music Player Daemon. Jun 05 14:26:37 integro systemd[1]: Starting Music Player Daemon... Jun 05 14:26:37 integro kernel: aml_tdm_open Jun 05 14:26:37 integro kernel: Not init audio effects Jun 05 14:26:37 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 05 14:26:37 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jun 05 14:26:37 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 05 14:26:37 integro kernel: aml_tdm_open Jun 05 14:26:37 integro kernel: Not init audio effects Jun 05 14:26:37 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 05 14:26:37 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jun 05 14:26:37 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 05 14:26:37 integro kernel: aml_tdm_open Jun 05 14:26:37 integro kernel: Not init audio effects Jun 05 14:26:37 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 05 14:26:37 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jun 05 14:26:37 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 05 14:26:37 integro volumio[3175]: info: QobuzConnect: setDeactiveState invoked Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:37 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:37 integro volumio[3175]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:37 integro sudo[32425]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 05 14:26:37 integro sudo[32425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro sudo[32425]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro sudo[32437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 05 14:26:37 integro sudo[32437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro sudo[32443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 05 14:26:37 integro sudo[32443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro sudo[32437]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro sudo[32443]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::volumioGetState Jun 05 14:26:37 integro volumio[3175]: info: CorePlayQueue::getTrack 49 Jun 05 14:26:37 integro volumio[3175]: info: Shairport-Sync Started Jun 05 14:26:37 integro sudo[32451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 05 14:26:37 integro sudo[32451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro sudo[32451]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro sudo[32454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 05 14:26:37 integro sudo[32454]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:37 integro qobuz-connect[32373]: 20250605 14:26:37.819 [32373.32373] INFO SampleApp: Stopping Local configuration server Jun 05 14:26:37 integro systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 05 14:26:37 integro qobuz-connect[32373]: 20250605 14:26:37.829 [32373.32373] INFO SampleApp: shat down connection on UNIX socket Jun 05 14:26:37 integro systemd[1]: qobuz-connect.service: Succeeded. Jun 05 14:26:37 integro systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 05 14:26:37 integro systemd[1]: Started Volumio Qobuz Connect Service. Jun 05 14:26:37 integro sudo[32454]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:37 integro volumio[3175]: info: camilladsp service started and running in background, instance 1 Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:37 integro volumio[3175]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 05 14:26:37 integro volumio[3175]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 05 14:26:37 integro volumio[3175]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 05 14:26:37 integro volumio[3175]: info: FusionDsp loaded Jun 05 14:26:37 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 14:26:38 integro sudo[32469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 05 14:26:38 integro sudo[32469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 14:26:38 integro volumio[3175]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 05 14:26:38 integro volumio[3175]: info: Adding Signal Path Element [object Object] Jun 05 14:26:38 integro volumio[3175]: info: Adding fusiondspeq DSP Signal Path Element Jun 05 14:26:38 integro volumio[3175]: info: Additional DSP elements updated Jun 05 14:26:38 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:38 integro volumio[3175]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 14:26:38 integro volumio[3175]: info: Updating RAAT Signal Path Jun 05 14:26:38 integro volumio[3175]: info: FusionDsp - ---- installed callbackRead Jun 05 14:26:38 integro systemd[1]: Started FusionDsp Daemon. Jun 05 14:26:38 integro volumio[3175]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 14:26:38 integro sudo[32469]: pam_unix(sudo:session): session closed for user root Jun 05 14:26:38 integro volumio[3175]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 05 14:26:38 integro volumio[3175]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jun 05 14:26:38 integro volumio[3175]: at onErrorNT (internal/child_process.js:472:16) Jun 05 14:26:38 integro volumio[3175]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 05 14:26:38 integro volumio[3175]: at runNextTicks (internal/process/task_queues.js:64:3) Jun 05 14:26:38 integro volumio[3175]: at listOnTimeout (internal/timers.js:526:9) Jun 05 14:26:38 integro volumio[3175]: at processTimers (internal/timers.js:500:7) { Jun 05 14:26:38 integro volumio[3175]: errno: -2, Jun 05 14:26:38 integro volumio[3175]: code: 'ENOENT', Jun 05 14:26:38 integro volumio[3175]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 05 14:26:38 integro volumio[3175]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 05 14:26:38 integro volumio[3175]: spawnargs: [ Jun 05 14:26:38 integro volumio[3175]: '-p', Jun 05 14:26:38 integro volumio[3175]: 9876, Jun 05 14:26:38 integro volumio[3175]: '-o', Jun 05 14:26:38 integro volumio[3175]: '/tmp/camilladsp.log', Jun 05 14:26:38 integro volumio[3175]: '-l', Jun 05 14:26:38 integro volumio[3175]: 'warn', Jun 05 14:26:38 integro volumio[3175]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 05 14:26:38 integro volumio[3175]: ] Jun 05 14:26:38 integro volumio[3175]: } Jun 05 14:26:38 integro volumio[3175]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 14:26:39 integro sudo[32483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 14:25 Jun 05 14:26:39 integro sudo[32483]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="integro" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:44:19 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Integro" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Integro" VOLUMIO_HASH="6c2b38e1f172b41bc0f88a1e52260974"