-- Logs begin at Sun 2025-06-15 18:18:05 CEST, end at Sun 2025-06-15 18:24:09 CEST. -- Jun 15 18:23:00 ivan volumio[1071]: info: Setting up python3-pip (18.1-5) ... Jun 15 18:23:01 ivan volumio[1071]: info: Setting up libgcc-8-dev:amd64 (8.3.0-6) ... Jun 15 18:23:01 ivan volumio[1071]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Jun 15 18:23:02 ivan volumio[1071]: info: Setting up libstdc++-8-dev:amd64 (8.3.0-6) ... Jun 15 18:23:02 ivan volumio[1071]: info: Setting up gcc-8 (8.3.0-6) ... Jun 15 18:23:02 ivan volumio[1071]: info: Setting up libpython3-dev:amd64 (3.7.3-1) ... Jun 15 18:23:02 ivan volumio[1071]: info: Setting up gcc (4:8.3.0-1) ... Jun 15 18:23:02 ivan volumio[1071]: info: Setting up python3-secretstorage (2.3.1-2) ... Jun 15 18:23:03 ivan volumio[1071]: info: Setting up python3-dev (3.7.3-1) ... Jun 15 18:23:03 ivan volumio[1071]: info: Setting up g++-8 (8.3.0-6) ... Jun 15 18:23:03 ivan volumio[1071]: info: Setting up python3-keyring (17.1.1-1) ... Jun 15 18:23:04 ivan volumio[1071]: info: Setting up g++ (4:8.3.0-1) ... Jun 15 18:23:04 ivan volumio[1071]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Jun 15 18:23:04 ivan volumio[1071]: info: Setting up build-essential (12.6) ... Jun 15 18:23:04 ivan volumio[1071]: info: Processing triggers for man-db (2.8.5-2) ... Jun 15 18:23:09 ivan volumio[1071]: info: Processing triggers for libc-bin (2.28-10+deb10u1) ... Jun 15 18:23:12 ivan sudo[2134]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:12 ivan sudo[3473]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jun 15 18:23:12 ivan sudo[3473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:12 ivan volumio[1071]: info: lib/systemd/system/fusiondsp.service Jun 15 18:23:12 ivan sudo[3473]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:15 ivan volumio[1071]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jun 15 18:23:15 ivan volumio[1071]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jun 15 18:23:15 ivan volumio[1071]: info: Jun 15 18:23:15 ivan volumio[1071]: info: cgui-1.0.0.zip opened Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/LICENSE.txt Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/README.md Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/build/ Jun 15 18:23:15 ivan sudo[3478]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jun 15 18:23:15 ivan sudo[3478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/robots.txt Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/logo512.png Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/asset-manifest.json Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/index.html Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/.put_statics_here Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/favicon.ico Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/logo192.png Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jun 15 18:23:15 ivan sudo[3478]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/manifest.json Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/css-variables.css Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/service-worker.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/build/static/ Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/build/static/css/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jun 15 18:23:15 ivan sudo[3480]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jun 15 18:23:15 ivan sudo[3480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/build/static/media/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/build/static/js/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jun 15 18:23:15 ivan sudo[3480]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.71e11955.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jun 15 18:23:15 ivan sudo[3482]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jun 15 18:23:15 ivan sudo[3482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/config/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/config/camillagui.yml Jun 15 18:23:15 ivan sudo[3482]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/config/gui-config.yml Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/backend/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/filters_test.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/version.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/filters.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/settings.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/views.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/filterdefaults.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__init__.py Jun 15 18:23:15 ivan volumio[1071]: info: creating directory: cgui/backend/__pycache__/ Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/routes.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/backend/filemanagement.py Jun 15 18:23:15 ivan volumio[1071]: info: extracting: cgui/main.py Jun 15 18:23:20 ivan volumio[1071]: info: CoreCommandRouter::volumioGetState Jun 15 18:23:20 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:21 ivan volumio[1071]: info: CoreCommandRouter::volumioRandom Jun 15 18:23:21 ivan volumio[1071]: info: CoreCommandRouter::writePlayerControls Jun 15 18:23:21 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:21 ivan volumio[1071]: info: CoreStateMachine::setRandom true Jun 15 18:23:21 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:21 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:21 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:21 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:22 ivan volumio[1071]: info: CoreCommandRouter::volumioRandom Jun 15 18:23:22 ivan volumio[1071]: info: CoreCommandRouter::writePlayerControls Jun 15 18:23:22 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:22 ivan volumio[1071]: info: CoreStateMachine::setRandom false Jun 15 18:23:22 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:22 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:22 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:22 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:23 ivan volumio[1071]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jun 15 18:23:23 ivan volumio[1071]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-c354glbb Jun 15 18:23:26 ivan volumio[1071]: info: CoreCommandRouter::volumioGetQueue Jun 15 18:23:26 ivan volumio[1071]: info: CoreStateMachine::getQueue Jun 15 18:23:26 ivan volumio[1071]: info: CorePlayQueue::getQueue Jun 15 18:23:27 ivan volumio[1071]: info: Collecting PyYAML (from camilladsp==1.0.0) Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioPlay Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::play index undefined Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::startPlaybackTimer Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::clearAddPlayTracks USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand stop took 8 milliseconds Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces system playlist update Jun 15 18:23:28 ivan volumio[1071]: info: Ignoring MPD Status Update Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand clear took 7 milliseconds Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand add "USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac" Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces system playlist update Jun 15 18:23:28 ivan volumio[1071]: info: Ignoring MPD Status Update Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces system playlist update Jun 15 18:23:28 ivan volumio[1071]: info: Ignoring MPD Status Update Jun 15 18:23:28 ivan volumio[1071]: error: updateQueue error: null Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces system playlist update Jun 15 18:23:28 ivan volumio[1071]: info: Ignoring MPD Status Update Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 52ms Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand add "USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac" took 44 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 40ms Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 27ms Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand play Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces system playlist update Jun 15 18:23:28 ivan volumio[1071]: info: Ignoring MPD Status Update Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces system playlist update Jun 15 18:23:28 ivan volumio[1071]: info: Ignoring MPD Status Update Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioGetState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 65ms Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand play took 49 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 47ms Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 47ms Jun 15 18:23:28 ivan volumio[1071]: error: Upnp client error: Error: This socket has been ended by the other party Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand status took 35 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand status took 27 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand status took 21 milliseconds Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:28 ivan volumio[1071]: info: Jun 15 18:23:28 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand status took 38 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand status took 16 milliseconds Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:28 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus stop Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"419 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:28 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:28 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"601 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:28 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:28 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 221ms Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 255ms Jun 15 18:23:28 ivan volumio[1071]: info: ------------------------------ 243ms Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand status took 196 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 177 milliseconds Jun 15 18:23:28 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 166 milliseconds Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:28 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:28 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"581 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:28 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:28 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:28 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:28 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:28 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:29 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"581 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:29 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:29 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:29 ivan volumio[1071]: info: ------------------------------ 360ms Jun 15 18:23:29 ivan volumio[1071]: info: ------------------------------ 324ms Jun 15 18:23:29 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 146 milliseconds Jun 15 18:23:29 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:29 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"581 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:29 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:29 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:29 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:29 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:29 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:29 ivan volumio[1071]: info: ------------------------------ 428ms Jun 15 18:23:29 ivan volumio[1071]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Jun 15 18:23:30 ivan volumio[1071]: info: Installing build dependencies: started Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioSeek Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::seek Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: TRACKBLOCK {"uri":"mnt/USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","service":"mpd","name":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=304&web=Danheim/Mannavegr%20%5BOriginal%20Bandcamp%5D/extralarge&path=%2FUSB%2F3137-6562%2FViking%2FDanheim&metadata=false","duration":225,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::startPlaybackTimer Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::seek Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: error: null Jun 15 18:23:32 ivan volumio[1071]: info: Jun 15 18:23:32 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:32 ivan volumio[1071]: info: Jun 15 18:23:32 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:32 ivan volumio[1071]: info: Jun 15 18:23:32 ivan volumio[1071]: ---------------------------- MPD announces state update: player Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::getState Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Jun 15 18:23:32 ivan volumio[1071]: info: sendMpdCommand status took 34 milliseconds Jun 15 18:23:32 ivan volumio[1071]: info: sendMpdCommand status took 22 milliseconds Jun 15 18:23:32 ivan volumio[1071]: info: sendMpdCommand status took 21 milliseconds Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::parseState Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 18:23:32 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 15 18:23:32 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 15 18:23:32 ivan volumio[1071]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:32 ivan volumio[1071]: verbose: ControllerMpd::parseTrackInfo Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:32 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:32 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:32 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:32 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: info: ControllerMpd::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::servicePushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29842,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Gripir","artist":"Danheim","album":"Mannavegr [Original Bandcamp]","uri":"USB/3137-6562/Viking/Danheim/Danheim - Mannavegr [Original Bandcamp] - 01 - Gripir.flac","trackType":"flac"} Jun 15 18:23:32 ivan volumio[1071]: verbose: CURRENT POSITION 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::syncState stateService play Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::syncState currentStatus play Jun 15 18:23:32 ivan volumio[1071]: info: Received an update from plugin. extracting info from payload Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:32 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:32 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:32 ivan volumio[1071]: info: ------------------------------ 236ms Jun 15 18:23:32 ivan volumio[1071]: info: ------------------------------ 219ms Jun 15 18:23:32 ivan volumio[1071]: info: ------------------------------ 218ms Jun 15 18:23:33 ivan kernel: perf: interrupt took too long (5032 > 5020), lowering kernel.perf_event_max_sample_rate to 39500 Jun 15 18:23:34 ivan volumio[1071]: info: VolumeController::SetAlsaVolume1 Jun 15 18:23:34 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:34 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:34 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:34 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:35 ivan volumio[1071]: info: VolumeController::SetAlsaVolume2 Jun 15 18:23:35 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:35 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:35 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:35 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:47 ivan volumio[1071]: info: VolumeController::SetAlsaVolume5 Jun 15 18:23:47 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:47 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:47 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:47 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:47 ivan volumio[1071]: info: VolumeController::SetAlsaVolume7 Jun 15 18:23:47 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:47 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:47 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:47 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:47 ivan volumio[1071]: info: VolumeController::SetAlsaVolume8 Jun 15 18:23:47 ivan volumio[1071]: info: CoreStateMachine::pushState Jun 15 18:23:47 ivan volumio[1071]: info: CorePlayQueue::getTrack 0 Jun 15 18:23:47 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 18:23:47 ivan volumio[1071]: info: CoreCommandRouter::volumioPushState Jun 15 18:23:48 ivan ntpd[1056]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 15 18:23:52 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 18:23:52 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 18:23:58 ivan volumio[1071]: info: Enabling plugin fusiondsp Jun 15 18:23:58 ivan volumio[1071]: info: Loading plugin "fusiondsp"... Jun 15 18:23:58 ivan volumio[1071]: info: Preparing to generate the ALSA configuration file Jun 15 18:23:59 ivan volumio[1071]: info: Asound.conf file unchanged, so no further update is needed Jun 15 18:23:59 ivan volumio[1071]: info: Output device has changed, restarting MPD Jun 15 18:23:59 ivan volumio[1071]: info: Output device has changed, restarting Shairport Sync Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 18:23:59 ivan sudo[3700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 18:23:59 ivan sudo[3700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:59 ivan sudo[3700]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:59 ivan sudo[3702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 18:23:59 ivan sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:59 ivan systemd[1]: Stopping Music Player Daemon... Jun 15 18:23:59 ivan volumio[1071]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 18:23:59 ivan volumio[1071]: info: PLUGIN START: fusiondsp Jun 15 18:23:59 ivan volumio[1071]: info: Loading i18n strings for locale es Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:23:59 ivan volumio[1071]: info: FusionDsp - mixtype--------------------- Hardware Jun 15 18:23:59 ivan volumio[1071]: info: Preparing to generate the ALSA configuration file Jun 15 18:23:59 ivan volumio[1071]: info: Done. Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: MPD Permissions set Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 18:23:59 ivan volumio[1071]: info: Asound.conf file unchanged, so no further update is needed Jun 15 18:23:59 ivan volumio[1071]: info: Output device has changed, restarting MPD Jun 15 18:23:59 ivan systemd[1]: mpd.service: Succeeded. Jun 15 18:23:59 ivan systemd[1]: Stopped Music Player Daemon. Jun 15 18:23:59 ivan systemd[1]: Starting Music Player Daemon... Jun 15 18:23:59 ivan volumio[1071]: info: Output device has changed, restarting Shairport Sync Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:23:59 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 18:23:59 ivan sudo[3709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 15 18:23:59 ivan sudo[3709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:59 ivan sudo[3709]: pam_unix(sudo:session): session closed for user root Jun 15 18:23:59 ivan sudo[3712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 15 18:23:59 ivan sudo[3712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:23:59 ivan systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 15 18:23:59 ivan systemd[1]: mpd.service: Succeeded. Jun 15 18:23:59 ivan systemd[1]: Stopped Music Player Daemon. Jun 15 18:23:59 ivan systemd[1]: Starting Music Player Daemon... Jun 15 18:24:00 ivan volumio[1071]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 15 18:24:00 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:24:00 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:24:00 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:24:00 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 18:24:00 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 15 18:24:00 ivan sudo[3718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 15 18:24:00 ivan sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:24:00 ivan sudo[3718]: pam_unix(sudo:session): session closed for user root Jun 15 18:24:00 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:24:00 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:01 ivan volumio[1071]: info: MPD Permissions set Jun 15 18:24:01 ivan volumio[1071]: info: FusionDsp - undefined Jun 15 18:24:01 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:02 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:03 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:04 ivan mpd[3726]: Jun 15 18:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 15 18:24:04 ivan systemd[1]: Started Music Player Daemon. Jun 15 18:24:04 ivan sudo[3712]: pam_unix(sudo:session): session closed for user root Jun 15 18:24:04 ivan sudo[3702]: pam_unix(sudo:session): session closed for user root Jun 15 18:24:04 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:04 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:05 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:05 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:06 ivan volumio[1071]: info: FusionDsp - Jun 15 18:24:07 ivan volumio[1071]: info: camilladsp service started and running in background, instance 1 Jun 15 18:24:07 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 18:24:07 ivan volumio[1071]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 15 18:24:07 ivan volumio[1071]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 15 18:24:07 ivan volumio[1071]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 15 18:24:07 ivan volumio[1071]: info: FusionDsp loaded Jun 15 18:24:07 ivan volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 18:24:07 ivan volumio[1071]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 15 18:24:07 ivan volumio[1071]: info: Adding Signal Path Element [object Object] Jun 15 18:24:07 ivan volumio[1071]: info: Adding fusiondspeq DSP Signal Path Element Jun 15 18:24:07 ivan sudo[3756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 15 18:24:07 ivan volumio[1071]: info: FusionDsp - ---- installed callbackRead Jun 15 18:24:07 ivan sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 18:24:07 ivan volumio[1071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 18:24:07 ivan volumio[1071]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 15 18:24:07 ivan volumio[1071]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jun 15 18:24:07 ivan volumio[1071]: at onErrorNT (internal/child_process.js:472:16) Jun 15 18:24:07 ivan volumio[1071]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 15 18:24:07 ivan volumio[1071]: at runNextTicks (internal/process/task_queues.js:64:3) Jun 15 18:24:07 ivan volumio[1071]: at listOnTimeout (internal/timers.js:526:9) Jun 15 18:24:07 ivan volumio[1071]: at processTimers (internal/timers.js:500:7) { Jun 15 18:24:07 ivan volumio[1071]: errno: -2, Jun 15 18:24:07 ivan volumio[1071]: code: 'ENOENT', Jun 15 18:24:07 ivan volumio[1071]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 15 18:24:07 ivan volumio[1071]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 15 18:24:07 ivan volumio[1071]: spawnargs: [ Jun 15 18:24:07 ivan volumio[1071]: '-p', Jun 15 18:24:07 ivan volumio[1071]: 9876, Jun 15 18:24:07 ivan volumio[1071]: '-o', Jun 15 18:24:07 ivan volumio[1071]: '/tmp/camilladsp.log', Jun 15 18:24:07 ivan volumio[1071]: '-l', Jun 15 18:24:07 ivan volumio[1071]: 'warn', Jun 15 18:24:07 ivan volumio[1071]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 15 18:24:07 ivan volumio[1071]: ] Jun 15 18:24:07 ivan volumio[1071]: } Jun 15 18:24:07 ivan volumio[1071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 18:24:07 ivan systemd[1]: Started FusionDsp Daemon. Jun 15 18:24:07 ivan sudo[3756]: pam_unix(sudo:session): session closed for user root Jun 15 18:24:09 ivan sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 18:23 Jun 15 18:24:09 ivan sudo[3768]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"