-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2026-02-26 11:36:01 CET. -- Feb 26 11:35:00 studirella volumio[864]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... Feb 26 11:35:00 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:00.328+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:00 studirella volumio[864]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... Feb 26 11:35:00 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:00.366+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:02 studirella volumio[864]: info: Setting up python3-pip (18.1-5+rpt1) ... Feb 26 11:35:02 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:02.870+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:05 studirella volumio[864]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Feb 26 11:35:05 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:05.207+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:05 studirella go-librespot[1079]: time="2026-02-26T11:35:05+01:00" level=trace msg="sent dealer ping" Feb 26 11:35:05 studirella go-librespot[1079]: time="2026-02-26T11:35:05+01:00" level=trace msg="received dealer pong" Feb 26 11:35:06 studirella volumio[864]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Feb 26 11:35:06 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:06.649+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:06 studirella volumio[864]: info: Setting up python3-secretstorage (2.3.1-2) ... Feb 26 11:35:06 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:06.687+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:08 studirella volumio[864]: info: Setting up python3-dev (3.7.3-1) ... Feb 26 11:35:08 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:08.084+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:08 studirella volumio[864]: info: Setting up python3-keyring (17.1.1-1) ... Feb 26 11:35:08 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:08.112+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:09 studirella volumio[864]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Feb 26 11:35:09 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:09.629+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=trace msg="emitting websocket event: not_playing" Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:52eYVUkFTOVozbVFIaFrnV" Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:album:3MATDdrpHmQCmuOcozZjDa","uri":"spotify:track:3oHkMCVJyOcjg5FhfLc2Rq","play_origin":"album"}} Feb 26 11:35:11 studirella volumio[864]: error: Failed to decode event: not_playing Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=trace msg="emitting websocket event: will_play" Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=info msg="loaded track \"Tony Tone\" (paused: false, position: 0ms, duration: 208371ms, prefetched: true)" uri="spotify:track:52eYVUkFTOVozbVFIaFrnV" Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:3MATDdrpHmQCmuOcozZjDa","uri":"spotify:track:52eYVUkFTOVozbVFIaFrnV","play_origin":"album"}} Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=trace msg="scheduling prefetch in 179s" Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=trace msg="emitting websocket event: metadata" Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:52eYVUkFTOVozbVFIaFrnV","name":"Tony Tone","artist_names":["A$AP Rocky"],"album_name":"TESTING","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bfcb8d00cee4f8257a6b7fe1","position":0,"duration":208371,"release_date":"year:2018 month:5 day:25","track_number":3,"disc_number":1}} Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 11:35:11 studirella go-librespot[1079]: time="2026-02-26T11:35:11+01:00" level=trace msg="emitting websocket event: playing" Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:album:3MATDdrpHmQCmuOcozZjDa","uri":"spotify:track:52eYVUkFTOVozbVFIaFrnV","resume":false,"play_origin":"album"}} Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: PUSH STATE SPOTIFY Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: {"status":"play","service":"spop","title":"Tony Tone","artist":"A$AP Rocky","album":"TESTING","albumart":"https://i.scdn.co/image/ab67616d00001e02bfcb8d00cee4f8257a6b7fe1","uri":"spotify:track:52eYVUkFTOVozbVFIaFrnV","trackType":"spotify","seek":1000,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 26 11:35:11 studirella volumio[864]: info: CoreCommandRouter::servicePushState Feb 26 11:35:11 studirella volumio[864]: info: CoreStateMachine::pushState Feb 26 11:35:11 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 11:35:11 studirella volumio[864]: info: CoreCommandRouter::volumioPushState Feb 26 11:35:11 studirella volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 11:35:12 studirella volumio[864]: SPOTIFY: PUSH STATE SPOTIFY Feb 26 11:35:12 studirella volumio[864]: SPOTIFY: {"status":"play","service":"spop","title":"Tony Tone","artist":"A$AP Rocky","album":"TESTING","albumart":"https://i.scdn.co/image/ab67616d00001e02bfcb8d00cee4f8257a6b7fe1","uri":"spotify:track:52eYVUkFTOVozbVFIaFrnV","trackType":"spotify","seek":1000,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 26 11:35:12 studirella volumio[864]: info: CoreCommandRouter::servicePushState Feb 26 11:35:12 studirella volumio[864]: info: CoreStateMachine::pushState Feb 26 11:35:12 studirella volumio[864]: info: CoreCommandRouter::volumioPushState Feb 26 11:35:12 studirella volumio[864]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 11:35:12 studirella sudo[5234]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:12 studirella sudo[5963]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Feb 26 11:35:12 studirella sudo[5963]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:12 studirella volumio[864]: info: lib/systemd/system/fusiondsp.service Feb 26 11:35:12 studirella sudo[5963]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:12 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:12.820+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:15 studirella volumio[864]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Feb 26 11:35:15 studirella volumio[864]: info: more info at http://www.winimage.com/zLibDll/unzip.html Feb 26 11:35:15 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:15.819+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:15 studirella volumio[864]: info: Feb 26 11:35:15 studirella volumio[864]: info: cgui-1.0.0.zip opened Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/LICENSE.txt Feb 26 11:35:15 studirella sudo[5967]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/README.md Feb 26 11:35:15 studirella sudo[5967]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/build/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/robots.txt Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/logo512.png Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/asset-manifest.json Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/index.html Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/.put_statics_here Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/favicon.ico Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/logo192.png Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Feb 26 11:35:15 studirella sudo[5967]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/manifest.json Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/css-variables.css Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/service-worker.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/build/static/ Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/build/static/css/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Feb 26 11:35:15 studirella sudo[5969]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Feb 26 11:35:15 studirella sudo[5969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/main.1503f6cd.css Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/build/static/media/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/build/static/js/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.71e11955.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Feb 26 11:35:15 studirella sudo[5969]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.71e11955.js.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/config/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/config/camillagui.yml Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Feb 26 11:35:15 studirella sudo[5971]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/config/gui-config.yml Feb 26 11:35:15 studirella sudo[5971]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:15 studirella volumio[864]: info: creating directory: cgui/backend/ Feb 26 11:35:15 studirella volumio[864]: info: extracting: cgui/backend/filters_test.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/version.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/filters.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/settings.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/views.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/filterdefaults.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__init__.py Feb 26 11:35:16 studirella volumio[864]: info: creating directory: cgui/backend/__pycache__/ Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/routes.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/backend/filemanagement.py Feb 26 11:35:16 studirella volumio[864]: info: extracting: cgui/main.py Feb 26 11:35:16 studirella sudo[5971]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.751+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.756+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.760+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.765+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.770+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.776+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.781+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.782+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.786+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.788+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.790+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.793+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.794+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.798+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.800+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.813+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.819+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.829+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.843+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.846+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.851+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.853+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.856+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.858+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.866+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.867+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.872+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.877+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.880+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.881+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.887+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.889+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.892+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.897+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.897+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.900+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.906+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.908+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.912+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.921+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.929+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.933+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.939+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.939+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.944+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.946+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.951+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.955+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.957+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.959+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.964+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.967+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.970+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.972+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.975+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.977+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.985+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.987+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.991+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:16 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:16.998+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.008+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.012+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.015+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.022+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.025+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.032+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.032+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.037+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.040+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.043+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.055+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.056+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:17 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:17.057+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:24 studirella go-librespot[1079]: time="2026-02-26T11:35:24+01:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:52eYVUkFTOVozbVFIaFrnV" Feb 26 11:35:29 studirella volumio[864]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Feb 26 11:35:29 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:29.275+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:29 studirella volumio[864]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Feb 26 11:35:29 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:29.304+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:29 studirella volumio[864]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-gpf22zru Feb 26 11:35:29 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:29.331+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:29 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:29.339+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.63:57760 Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.353+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.63:57760 @ 0x1d98150" latency=9.809099ms timeout=10s Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.353+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.353+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.63:57760 @ 0x1d98150" latency=10.67462ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 26 11:35:30 studirella volumio[864]: info: Received Get System Info Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 11:35:30 studirella volumio[864]: info: Discovery: Getting this device information Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.367+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" name=Studirella Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.375+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" language=de Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.383+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" timezone=Europe/Berlin Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.386+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" available=true connected=false macAddress= ip4Address= ip6Address= Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.391+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" available=true connected=true macAddress=6c:1f:f7:35:2e:d8 ip4Address=192.168.0.83/24 ip6Address= ssid=Gaesteklo Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.392+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" setupComplete=true Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 11:35:30 studirella volumio[864]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 26 11:35:30 studirella volumio[864]: Card hw:1 'Headphones'/'bcm2835 Headphones' Feb 26 11:35:30 studirella volumio[864]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 26 11:35:30 studirella volumio[864]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 26 11:35:30 studirella volumio[864]: Card hw:1 'Headphones'/'bcm2835 Headphones' Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.952+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" selectedOutputId=1 Feb 26 11:35:30 studirella volumio[864]: info: Received Get System Info Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 11:35:30 studirella volumio[864]: info: Discovery: Getting this device information Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:30 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.992+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" currentVersion=3.908 latestVersion=3.908 Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.993+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.63:57760 @ 0x1d98150" status=UPDATE_STATUS_NONE progress=0 Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.994+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" userId=u20L7HHy6KdYNMngxgRrs0Js7ys2 Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.995+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" providers=9 Feb 26 11:35:30 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:30.996+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" plugins=54 Feb 26 11:35:31 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:31 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:31.013+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" state=STATUS_PLAYING positionMs=19000 volume=100 Feb 26 11:35:31 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:31.014+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.63:57760 @ 0x1d98150" id=spotify:track:52eYVUkFTOVozbVFIaFrnV title="Tony Tone" Feb 26 11:35:31 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:31.243+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.63:57760 @ 0x1d98150" latency=5.250901ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 26 11:35:32 studirella sudo[6033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 11:35:32 studirella sudo[6033]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:32 studirella sudo[6033]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:32 studirella sudo[6035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 11:35:32 studirella sudo[6035]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:32 studirella sudo[6035]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:32 studirella volumio[864]: verbose: New Socket.io Connection to 192.168.0.83 from 192.168.0.63 UA: Mozilla/5.0 (Linux; Android 16; motorola edge 60 Build/W1VC36H.14-20-19; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 26 11:35:32 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 11:35:32 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 26 11:35:33 studirella sudo[6039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 11:35:33 studirella sudo[6039]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:33 studirella sudo[6041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 11:35:33 studirella sudo[6039]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:33 studirella sudo[6041]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:33 studirella sudo[6041]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:33 studirella volumio[864]: verbose: New Socket.io Connection to 192.168.0.83 from 192.168.0.63 UA: Mozilla/5.0 (Linux; Android 16; motorola edge 60 Build/W1VC36H.14-20-19; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 11:35:33 studirella volumio[864]: info: Received Get System Info Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 11:35:33 studirella volumio[864]: info: Discovery: Getting this device information Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:33 studirella volumio[864]: info: Listing playlists Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 11:35:33 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 26 11:35:34 studirella volumio[864]: info: Collecting PyYAML (from camilladsp==1.0.0) Feb 26 11:35:34 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:34.567+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:35 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 11:35:35 studirella volumio[864]: info: Received Get System Info Feb 26 11:35:35 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 11:35:35 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 11:35:35 studirella volumio[864]: info: Discovery: Getting this device information Feb 26 11:35:35 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:35 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:35 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 26 11:35:35 studirella go-librespot[1079]: time="2026-02-26T11:35:35+01:00" level=trace msg="received accesspoint ping" Feb 26 11:35:35 studirella go-librespot[1079]: time="2026-02-26T11:35:35+01:00" level=trace msg="received accesspoint pong ack" Feb 26 11:35:35 studirella go-librespot[1079]: time="2026-02-26T11:35:35+01:00" level=trace msg="sent dealer ping" Feb 26 11:35:35 studirella go-librespot[1079]: time="2026-02-26T11:35:35+01:00" level=trace msg="received dealer pong" Feb 26 11:35:37 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 11:35:37 studirella volumio[864]: info: Received Get System Info Feb 26 11:35:37 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 11:35:37 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 11:35:37 studirella volumio[864]: info: Discovery: Getting this device information Feb 26 11:35:37 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:37 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:37 studirella go-librespot[1079]: time="2026-02-26T11:35:37+01:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:52eYVUkFTOVozbVFIaFrnV" Feb 26 11:35:38 studirella volumio[864]: info: Downloading https://www.piwheels.org/simple/pyyaml/PyYAML-6.0.1-cp37-cp37m-linux_armv7l.whl (45kB) Feb 26 11:35:38 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:38.413+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:38 studirella volumio[864]: info: Collecting websocket_client (from camilladsp==1.0.0) Feb 26 11:35:38 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:38.772+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:39 studirella volumio[864]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) Feb 26 11:35:40 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:40.017+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:40 studirella volumio[864]: info: Building wheels for collected packages: camilladsp Feb 26 11:35:40 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:40.340+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:40 studirella volumio[864]: info: Running setup.py bdist_wheel for camilladsp: started Feb 26 11:35:40 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:40.366+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:43 studirella volumio[864]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Feb 26 11:35:43 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:43.192+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:43 studirella volumio[864]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-c14klee4/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Feb 26 11:35:43 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:43.226+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:43 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 11:35:43 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 26 11:35:43 studirella volumio[864]: info: Successfully built camilladsp Feb 26 11:35:43 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:43.310+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:43 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 11:35:43 studirella volumio[864]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Feb 26 11:35:43 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:43.853+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:44 studirella volumio[864]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Feb 26 11:35:44 studirella volumio5-onboarding[1296]: time=2026-02-26T11:35:44.892+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 11:35:50 studirella go-librespot[1079]: time="2026-02-26T11:35:50+01:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:52eYVUkFTOVozbVFIaFrnV" Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 11:35:51 studirella volumio[864]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 26 11:35:51 studirella volumio[864]: info: Received Get System Version Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 11:35:51 studirella volumio[864]: info: Received Get System Info Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 11:35:51 studirella volumio[864]: info: Discovery: Getting this device information Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::volumioGetState Feb 26 11:35:51 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:55 studirella volumio[864]: info: Enabling plugin fusiondsp Feb 26 11:35:55 studirella volumio[864]: info: Loading plugin "fusiondsp"... Feb 26 11:35:56 studirella volumio[864]: info: Preparing to generate the ALSA configuration file Feb 26 11:35:56 studirella volumio[864]: info: Asound.conf file unchanged, so no further update is needed Feb 26 11:35:56 studirella volumio[864]: info: Output device has changed, restarting MPD Feb 26 11:35:56 studirella sudo[6080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 11:35:56 studirella sudo[6080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:56 studirella volumio[864]: info: Output device has changed, restarting Shairport Sync Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 11:35:56 studirella sudo[6080]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:56 studirella sudo[6082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 11:35:56 studirella sudo[6082]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:56 studirella volumio[864]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 11:35:56 studirella systemd[1]: Stopping Music Player Daemon... Feb 26 11:35:56 studirella volumio[864]: info: PLUGIN START: fusiondsp Feb 26 11:35:56 studirella volumio[864]: info: Loading i18n strings for locale de Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: FusionDsp - mixtype--------------------- Hardware Feb 26 11:35:56 studirella volumio[864]: info: Preparing to generate the ALSA configuration file Feb 26 11:35:56 studirella volumio[864]: info: Done. Feb 26 11:35:56 studirella volumio[864]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 26 11:35:56 studirella volumio[864]: info: Reading ALSA contributions from plugins. Feb 26 11:35:56 studirella volumio[864]: info: MPD Permissions set Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:56 studirella volumio[864]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 11:35:56 studirella volumio[864]: info: Starting Shairport Sync Feb 26 11:35:56 studirella volumio[864]: info: FusionDsp - Feb 26 11:35:56 studirella sudo[6093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 11:35:56 studirella sudo[6093]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:57 studirella systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 26 11:35:57 studirella systemd[1]: shairport-sync.service: Succeeded. Feb 26 11:35:57 studirella systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 26 11:35:57 studirella systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 26 11:35:57 studirella systemd[1]: mpd.service: Succeeded. Feb 26 11:35:57 studirella systemd[1]: Stopped Music Player Daemon. Feb 26 11:35:57 studirella sudo[6093]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:57 studirella systemd[1]: Starting Music Player Daemon... Feb 26 11:35:57 studirella sudo[6109]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 11:35:57 studirella sudo[6109]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:57 studirella sudo[6109]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:58 studirella volumio[864]: info: Asound.conf file written Feb 26 11:35:58 studirella sudo[6175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 26 11:35:58 studirella sudo[6175]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:58 studirella sudo[6175]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:58 studirella volumio[864]: info: Output device has changed, restarting MPD Feb 26 11:35:58 studirella sudo[6181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 11:35:58 studirella sudo[6181]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:58 studirella volumio[864]: info: Output device has changed, restarting Shairport Sync Feb 26 11:35:58 studirella sudo[6181]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:58 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:58 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 11:35:58 studirella sudo[6184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 11:35:58 studirella sudo[6184]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:58 studirella systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 26 11:35:58 studirella systemd[1]: mpd.service: Succeeded. Feb 26 11:35:58 studirella systemd[1]: Stopped Music Player Daemon. Feb 26 11:35:58 studirella volumio[864]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 11:35:58 studirella systemd[1]: Starting Music Player Daemon... Feb 26 11:35:58 studirella volumio[864]: info: camilladsp service started and running in background, instance 1 Feb 26 11:35:58 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 11:35:58 studirella volumio[864]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 26 11:35:58 studirella volumio[864]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Feb 26 11:35:58 studirella volumio[864]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 26 11:35:58 studirella volumio[864]: info: FusionDsp loaded Feb 26 11:35:58 studirella volumio[864]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 11:35:59 studirella sudo[6200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 26 11:35:59 studirella sudo[6200]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:59 studirella sudo[6189]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 11:35:59 studirella sudo[6189]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 11:35:59 studirella sudo[6189]: pam_unix(sudo:session): session closed for user root Feb 26 11:35:59 studirella volumio[864]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 26 11:35:59 studirella volumio[864]: info: Adding Signal Path Element [object Object] Feb 26 11:35:59 studirella volumio[864]: info: Adding fusiondspeq DSP Signal Path Element Feb 26 11:35:59 studirella volumio[864]: info: FusionDsp - ---- installed callbackRead Feb 26 11:35:59 studirella volumio[864]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 11:35:59 studirella volumio[864]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Feb 26 11:35:59 studirella volumio[864]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Feb 26 11:35:59 studirella volumio[864]: at onErrorNT (internal/child_process.js:465:16) Feb 26 11:35:59 studirella volumio[864]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 26 11:35:59 studirella volumio[864]: at runNextTicks (internal/process/task_queues.js:62:3) Feb 26 11:35:59 studirella volumio[864]: at listOnTimeout (internal/timers.js:523:9) Feb 26 11:35:59 studirella volumio[864]: at processTimers (internal/timers.js:497:7) { Feb 26 11:35:59 studirella volumio[864]: errno: -2, Feb 26 11:35:59 studirella volumio[864]: code: 'ENOENT', Feb 26 11:35:59 studirella volumio[864]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Feb 26 11:35:59 studirella volumio[864]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Feb 26 11:35:59 studirella volumio[864]: spawnargs: [ Feb 26 11:35:59 studirella volumio[864]: '-p', Feb 26 11:35:59 studirella volumio[864]: 9876, Feb 26 11:35:59 studirella volumio[864]: '-o', Feb 26 11:35:59 studirella volumio[864]: '/tmp/camilladsp.log', Feb 26 11:35:59 studirella volumio[864]: '-l', Feb 26 11:35:59 studirella volumio[864]: 'warn', Feb 26 11:35:59 studirella volumio[864]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Feb 26 11:35:59 studirella volumio[864]: ] Feb 26 11:35:59 studirella volumio[864]: } Feb 26 11:35:59 studirella volumio[864]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 11:35:59 studirella systemd[1]: Started FusionDsp Daemon. Feb 26 11:35:59 studirella sudo[6200]: pam_unix(sudo:session): session closed for user root Feb 26 11:36:01 studirella sudo[6226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 11:35 Feb 26 11:36:01 studirella sudo[6226]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"