-- Logs begin at Tue 2025-05-27 10:07:01 EEST, end at Tue 2025-05-27 10:11:10 EEST. -- May 27 10:10:00 volumio volumio[2785]: info: Building dependency tree... May 27 10:10:00 volumio volumio[2785]: info: Reading state information... May 27 10:10:00 volumio volumio[2785]: info: All packages are up to date. May 27 10:10:00 volumio sudo[3136]: pam_unix(sudo:session): session closed for user root May 27 10:10:00 volumio sudo[3368]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 27 10:10:00 volumio sudo[3368]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:10:00 volumio volumio[2785]: info: Setting Geolocation for MyVolumio to eu5 May 27 10:10:00 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:00 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:00 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:00 volumio volumio[2785]: info: Reading package lists... May 27 10:10:00 volumio volumio[2785]: info: Building dependency tree... May 27 10:10:00 volumio volumio[2785]: info: Reading state information... May 27 10:10:00 volumio volumio[2785]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). May 27 10:10:00 volumio sudo[3368]: pam_unix(sudo:session): session closed for user root May 27 10:10:00 volumio volumio[2785]: info: python3-pip is already the newest version (18.1-5). May 27 10:10:00 volumio volumio[2785]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. May 27 10:10:00 volumio sudo[3372]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 27 10:10:00 volumio sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:10:00 volumio volumio[2785]: info: lib/systemd/system/fusiondsp.service May 27 10:10:00 volumio sudo[3372]: pam_unix(sudo:session): session closed for user root May 27 10:10:01 volumio volumio[2785]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 27 10:10:01 volumio volumio[2785]: info: Updating MyVolumio device info May 27 10:10:01 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:01 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:01 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:01 volumio volumio[2785]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 27 10:10:01 volumio volumio[2785]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 27 10:10:01 volumio volumio[2785]: info: May 27 10:10:01 volumio volumio[2785]: info: cgui-1.0.0.zip opened May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/LICENSE.txt May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/README.md May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/build/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/robots.txt May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/logo512.png May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/asset-manifest.json May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/index.html May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/.put_statics_here May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/favicon.ico May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/logo192.png May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/manifest.json May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/css-variables.css May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 27 10:10:01 volumio sudo[3376]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/service-worker.js May 27 10:10:01 volumio sudo[3376]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/build/static/ May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/build/static/css/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/build/static/media/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 27 10:10:01 volumio sudo[3376]: pam_unix(sudo:session): session closed for user root May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/build/static/js/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.71e11955.js May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/config/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/config/camillagui.yml May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/config/gui-config.yml May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/backend/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/filters_test.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/version.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/filters.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/settings.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/views.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/filterdefaults.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__init__.py May 27 10:10:01 volumio volumio[2785]: info: creating directory: cgui/backend/__pycache__/ May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 27 10:10:01 volumio sudo[3378]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 27 10:10:01 volumio sudo[3378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/routes.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/backend/filemanagement.py May 27 10:10:01 volumio volumio[2785]: info: extracting: cgui/main.py May 27 10:10:01 volumio sudo[3378]: pam_unix(sudo:session): session closed for user root May 27 10:10:01 volumio sudo[3380]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 27 10:10:01 volumio sudo[3380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:10:01 volumio sudo[3380]: pam_unix(sudo:session): session closed for user root May 27 10:10:02 volumio volumio[2785]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 May 27 10:10:02 volumio volumio[2785]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-poq4fni9 May 27 10:10:02 volumio volumio[2785]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 27 10:10:03 volumio volumio[2785]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages May 27 10:10:03 volumio volumio[2785]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) May 27 10:10:03 volumio volumio[2785]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) May 27 10:10:03 volumio volumio[2785]: info: Building wheels for collected packages: camilladsp May 27 10:10:03 volumio volumio[2785]: info: Running setup.py bdist_wheel for camilladsp: started May 27 10:10:03 volumio volumio[2785]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' May 27 10:10:03 volumio volumio[2785]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-wipx53qq/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 May 27 10:10:03 volumio volumio[2785]: info: Successfully built camilladsp May 27 10:10:04 volumio volumio[2785]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 May 27 10:10:04 volumio volumio[2785]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-ot8105ja May 27 10:10:05 volumio volumio[2785]: info: MYVOLUMIO: Adding device May 27 10:10:05 volumio volumio[2785]: info: MYVOLUMIO: Evaluating Server May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) May 27 10:10:05 volumio volumio[2785]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) May 27 10:10:05 volumio volumio[2785]: info: Building wheels for collected packages: camilladsp-plot May 27 10:10:05 volumio volumio[2785]: info: Running setup.py bdist_wheel for camilladsp-plot: started May 27 10:10:05 volumio volumio[2785]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' May 27 10:10:05 volumio volumio[2785]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-akat08bj/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 May 27 10:10:05 volumio volumio[2785]: info: Successfully built camilladsp-plot May 27 10:10:05 volumio volumio[2785]: info: Setting Geolocation for MyVolumio to eu5 May 27 10:10:05 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:05 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:05 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:05 volumio volumio[2785]: info: copying hw detection script May 27 10:10:05 volumio volumio[2785]: info: Detected cpu architecture as x86_64 May 27 10:10:05 volumio volumio[2785]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 27 10:10:06 volumio sudo[3505]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 27 10:10:06 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:10:06 volumio volumio[2785]: info: Reading package lists... May 27 10:10:06 volumio volumio[2785]: info: Building dependency tree... May 27 10:10:06 volumio volumio[2785]: info: Reading state information... May 27 10:10:06 volumio volumio[2785]: info: drc is already the newest version (3.2.2~dfsg0-2). May 27 10:10:06 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root May 27 10:10:06 volumio sudo[3111]: pam_unix(sudo:session): session closed for user root May 27 10:10:06 volumio volumio[2785]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. May 27 10:10:06 volumio volumio[2785]: info: Install script completed May 27 10:10:06 volumio volumio[2785]: info: Done installing plugin. May 27 10:10:06 volumio volumio[2785]: info: Enabling plugin fusiondsp May 27 10:10:06 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 10:10:06 volumio volumio[2785]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false}] May 27 10:10:06 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 27 10:10:06 volumio volumio[2785]: info: Folder /tmp/plugins removed May 27 10:10:06 volumio volumio[2785]: info: Folder /tmp/downloaded_plugin.zip removed May 27 10:10:06 volumio volumio[2785]: Plugin install end detected on script May 27 10:10:06 volumio volumio[2785]: info: Folder /data/temp removed May 27 10:10:06 volumio volumio[2785]: info: Updating MyVolumio device info May 27 10:10:06 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:06 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:06 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:06 volumio volumio[2785]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 27 10:10:12 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 10:10:12 volumio volumio[2785]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 27 10:10:12 volumio volumio[2785]: info: CoreCommandRouter::volumioGetState May 27 10:10:12 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:17 volumio volumio[2785]: info: Retrieving Cloud Streaming UI May 27 10:10:17 volumio volumio[2785]: info: Getting Tidal Cloud Configuration May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:17 volumio volumio[2785]: info: Getting Qobuz Cloud Configuration May 27 10:10:17 volumio volumio[2785]: info: Asking plugin for UI Config May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:17 volumio volumio[2785]: info: Getting Spotify Cloud Configuration May 27 10:10:17 volumio volumio[2785]: info: Asking plugin for UI Config May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:17 volumio volumio[2785]: info: Saving Spotify Acccount May 27 10:10:17 volumio volumio[2785]: info: Got Tidal Cloud Configuration May 27 10:10:17 volumio volumio[2785]: info: Got it May 27 10:10:17 volumio volumio[2785]: info: Got it May 27 10:10:17 volumio volumio[2785]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:17 volumio volumio[2785]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::volumioGetBrowseSources May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::volumioGetBrowseSources May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::volumioGetBrowseSources May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::volumioGetBrowseSources May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 10:10:17 volumio volumio[2785]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 27 10:10:17 volumio volumio[2785]: info: Completed starting MyVolumio Plugin May 27 10:10:17 volumio volumio[2785]: [Metrics] CommandRouter: 30s 215.33ms May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::volumiosetStartupVolume May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: VolumeController:: Setting startup Volume 70 May 27 10:10:17 volumio volumio[2785]: info: VolumeController::SetAlsaVolume70 May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:17 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:17 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 10:10:17 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:17 volumio volumio[2785]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 10:10:17 volumio volumio[2785]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 10:10:17 volumio volumio[2785]: error: FusionDsp - TypeError: Cannot read property 'stop' of null May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 10:10:18 volumio volumio-remote-updater[814]: No test mode May 27 10:10:18 volumio volumio-remote-updater[814]: No alpha test mode May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 10:10:18 volumio volumio[2785]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 10:10:18 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 27 10:10:19 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand rescan May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: info: May 27 10:10:19 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand rescan took 2 milliseconds May 27 10:10:19 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: info: May 27 10:10:19 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:19 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: info: May 27 10:10:19 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:19 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: info: May 27 10:10:19 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:19 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 3 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 2 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 2 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 1 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 1 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 1 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 2 milliseconds May 27 10:10:19 volumio volumio[2785]: info: sendMpdCommand status took 1 milliseconds May 27 10:10:19 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:25 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:25 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:25 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: No code May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:25 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: No code May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:25 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: No code May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:25 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: No code May 27 10:10:25 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:25 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:25 volumio volumio[2785]: info: ------------------------------ 5920ms May 27 10:10:25 volumio volumio[2785]: info: ------------------------------ 5920ms May 27 10:10:25 volumio volumio[2785]: info: ------------------------------ 5919ms May 27 10:10:25 volumio volumio[2785]: info: ------------------------------ 5919ms May 27 10:10:25 volumio volumio[2785]: info: BOOT COMPLETED May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 10:10:25 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 10:10:27 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 27 10:10:27 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand update May 27 10:10:27 volumio volumio[2785]: info: sendMpdCommand update took 1 milliseconds May 27 10:10:29 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 10:10:30 volumio volumio[2785]: info: MPD Database updated - AlbumList cache refreshed May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: database May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: MPD Database updated - AlbumList cache refreshed May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: database May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: MPD Database updated - AlbumList cache refreshed May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: database May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: MPD Database updated - AlbumList cache refreshed May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: database May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 54 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 53 milliseconds May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 104ms May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 105ms May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 135 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 136 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 136 milliseconds May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 169ms May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 169ms May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 218 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 218 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 218 milliseconds May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 254ms May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 255ms May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: May 27 10:10:30 volumio volumio[2785]: ---------------------------- MPD announces state update: update May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 282 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 282 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 282 milliseconds May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::getState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::sendMpdCommand status May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 23 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 22 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 22 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 22 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 22 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 21 milliseconds May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatetrue May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatefalse May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatefalse May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatefalse May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 354ms May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 355ms May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 95ms May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 94ms May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 74 milliseconds May 27 10:10:30 volumio volumio[2785]: info: sendMpdCommand status took 73 milliseconds May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: Command Router : Notfying DB Updatefalse May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::Close All Modals sent May 27 10:10:30 volumio volumio[2785]: verbose: ControllerMpd::parseState May 27 10:10:30 volumio volumio[2785]: info: ControllerMpd::pushState May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::servicePushState May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 10:10:30 volumio volumio[2785]: verbose: CURRENT POSITION 0 May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState stateService stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::syncState currentStatus stop May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: No code May 27 10:10:30 volumio volumio[2785]: info: CoreStateMachine::pushState May 27 10:10:30 volumio volumio[2785]: info: CorePlayQueue::getTrack 0 May 27 10:10:30 volumio volumio[2785]: info: CoreCommandRouter::volumioPushState May 27 10:10:30 volumio volumio[2785]: info: ------------------------------ 89ms May 27 10:10:33 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 10:10:33 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 10:10:33 volumio volumio[2785]: info: CURURI: music-library May 27 10:10:33 volumio volumio[2785]: info: Preload queue cleared May 27 10:10:35 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 10:10:35 volumio volumio[2785]: info: CURURI: music-library/USB May 27 10:10:35 volumio volumio[2785]: info: Preload queue cleared May 27 10:10:35 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 10:10:35 volumio volumio[2785]: info: CURURI: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96) May 27 10:10:35 volumio volumio[2785]: info: Preload queue cleared May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/01 Clannad - Dulaman (Seaweed).flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/02 Clannad - Cumha Eoghain Rua Ui Neeill (Lament For Owen Roe).flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/03 Clannad - Two Sisters.flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/04 Clannad - Eirigh Suas A Stoirin (Rise Up My Love).flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/05 Clannad - The Galtee Hunt.flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/06 Clannad - Eirigh Is Cuir Ort Do Chuid Eadaigh (Arise And Dress Yourself).flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/07 Clannad - Siuil A Run (Irish Love Song).flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/08 Clannad - Mo Mhaire.flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/09 Clannad - DTigeas A Damhsa (Children's Dance Song).flac May 27 10:10:35 volumio volumio[2785]: info: Preloading song: music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/10 Clannad - Cucanandy - The Jug Of Brown Ale.flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/01 Clannad - Dulaman (Seaweed).flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F01%20Clannad%20-%20Dulaman%20(Seaweed).flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/01 Clannad - Dulaman (Seaweed).flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/02 Clannad - Cumha Eoghain Rua Ui Neeill (Lament For Owen Roe).flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F02%20Clannad%20-%20Cumha%20Eoghain%20Rua%20Ui%20Neeill%20(Lament%20For%20Owen%20Roe).flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/02 Clannad - Cumha Eoghain Rua Ui Neeill (Lament For Owen Roe).flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/03 Clannad - Two Sisters.flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F03%20Clannad%20-%20Two%20Sisters.flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/03 Clannad - Two Sisters.flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/04 Clannad - Eirigh Suas A Stoirin (Rise Up My Love).flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F04%20Clannad%20-%20Eirigh%20Suas%20A%20Stoirin%20(Rise%20Up%20My%20Love).flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/04 Clannad - Eirigh Suas A Stoirin (Rise Up My Love).flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/05 Clannad - The Galtee Hunt.flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F05%20Clannad%20-%20The%20Galtee%20Hunt.flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/05 Clannad - The Galtee Hunt.flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/06 Clannad - Eirigh Is Cuir Ort Do Chuid Eadaigh (Arise And Dress Yourself).flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F06%20Clannad%20-%20Eirigh%20Is%20Cuir%20Ort%20Do%20Chuid%20Eadaigh%20(Arise%20And%20Dress%20Yourself).flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/06 Clannad - Eirigh Is Cuir Ort Do Chuid Eadaigh (Arise And Dress Yourself).flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/07 Clannad - Siuil A Run (Irish Love Song).flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F07%20Clannad%20-%20Siuil%20A%20Run%20(Irish%20Love%20Song).flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/07 Clannad - Siuil A Run (Irish Love Song).flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/08 Clannad - Mo Mhaire.flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F08%20Clannad%20-%20Mo%20Mhaire.flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/08 Clannad - Mo Mhaire.flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/09 Clannad - DTigeas A Damhsa (Children's Dance Song).flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F09%20Clannad%20-%20DTigeas%20A%20Damhsa%20(Children's%20Dance%20Song).flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/09 Clannad - DTigeas A Damhsa (Children's Dance Song).flac May 27 10:10:35 volumio volumio[2785]: info: Exploding uri music-library/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/10 Clannad - Cucanandy - The Jug Of Brown Ale.flac in service mpd May 27 10:10:35 volumio volumio[2785]: info: ALBUMART /albumart?cacheid=988&web=Clannad/Dulaman%20(LP)/extralarge&path=%2Fmnt%2FUSB%2F1976%20-%20Clannad%20-%20D%C3%BAlam%C3%A1n%20(Intercord.%20INT%20160.065%2C%20DE%2C%2024-96)%2F10%20Clannad%20-%20Cucanandy%20-%20The%20Jug%20Of%20Brown%20Ale.flac&metadata=false May 27 10:10:35 volumio volumio[2785]: info: URI /mnt/USB/1976 - Clannad - Dúlamán (Intercord. INT 160.065, DE, 24-96)/10 Clannad - Cucanandy - The Jug Of Brown Ale.flac May 27 10:10:49 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 10:10:49 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 27 10:10:53 volumio volumio[2785]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:10:53 volumio volumio[2785]: info: FusionDsp - May 27 10:10:53 volumio volumio[2785]: info: FusionDsp - undefined May 27 10:11:04 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 10:11:04 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 27 10:11:09 volumio volumio[2785]: info: Enabling plugin fusiondsp May 27 10:11:09 volumio volumio[2785]: info: Loading plugin "fusiondsp"... May 27 10:11:09 volumio volumio[2785]: info: Applying required configuration parameters for plugin fusiondsp May 27 10:11:09 volumio volumio[2785]: info: Preparing to generate the ALSA configuration file May 27 10:11:09 volumio volumio[2785]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 27 10:11:09 volumio volumio[2785]: info: Reading ALSA contributions from plugins. May 27 10:11:09 volumio volumio[2785]: info: Asound.conf file unchanged, so no further update is needed May 27 10:11:09 volumio volumio[2785]: info: Output device has changed, restarting MPD May 27 10:11:09 volumio sudo[3700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:11:09 volumio sudo[3700]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio volumio[2785]: info: Output device has changed, restarting Shairport Sync May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio sudo[3700]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:11:09 volumio sudo[3703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:11:09 volumio sudo[3703]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio systemd[1]: Stopping Music Player Daemon... May 27 10:11:09 volumio volumio[2785]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:11:09 volumio systemd[1]: mpd.service: Succeeded. May 27 10:11:09 volumio systemd[1]: Stopped Music Player Daemon. May 27 10:11:09 volumio volumio[2785]: info: PLUGIN START: fusiondsp May 27 10:11:09 volumio volumio[2785]: info: Loading i18n strings for locale ru May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: FusionDsp - mixtype--------------------- Hardware May 27 10:11:09 volumio volumio[2785]: info: Preparing to generate the ALSA configuration file May 27 10:11:09 volumio volumio[2785]: info: Done. May 27 10:11:09 volumio volumio[2785]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 27 10:11:09 volumio volumio[2785]: info: Reading ALSA contributions from plugins. May 27 10:11:09 volumio volumio[2785]: info: MPD Permissions set May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio systemd[1]: Starting Music Player Daemon... May 27 10:11:09 volumio volumio[2785]: info: Starting Shairport Sync May 27 10:11:09 volumio volumio[2785]: info: Asound.conf file unchanged, so no further update is needed May 27 10:11:09 volumio volumio[2785]: info: Output device has changed, restarting MPD May 27 10:11:09 volumio sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:11:09 volumio sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 10:11:09 volumio systemd[1]: shairport-sync.service: Succeeded. May 27 10:11:09 volumio sudo[3719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:11:09 volumio sudo[3719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 27 10:11:09 volumio sudo[3719]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 10:11:09 volumio sudo[3716]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio volumio[2785]: info: Output device has changed, restarting Shairport Sync May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:11:09 volumio sudo[3708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 10:11:09 volumio sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio sudo[3708]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio sudo[3724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:11:09 volumio sudo[3724]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 27 10:11:09 volumio systemd[1]: mpd.service: Succeeded. May 27 10:11:09 volumio systemd[1]: Stopped Music Player Daemon. May 27 10:11:09 volumio systemd[1]: Starting Music Player Daemon... May 27 10:11:09 volumio volumio[2785]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:11:09 volumio volumio[2785]: info: MPD Permissions set May 27 10:11:09 volumio volumio[2785]: info: Shairport-Sync Started May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 27 10:11:09 volumio volumio[2785]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:11:09 volumio volumio[2785]: info: Starting Shairport Sync May 27 10:11:09 volumio sudo[3732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 10:11:09 volumio sudo[3732]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio sudo[3732]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio sudo[3743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:11:09 volumio sudo[3743]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 10:11:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 10:11:09 volumio systemd[1]: shairport-sync.service: Succeeded. May 27 10:11:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 27 10:11:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 10:11:09 volumio sudo[3743]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio volumio[2785]: info: Shairport-Sync Started May 27 10:11:09 volumio mpd[3742]: May 27 10:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 27 10:11:09 volumio systemd[1]: Started Music Player Daemon. May 27 10:11:09 volumio sudo[3724]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio sudo[3703]: pam_unix(sudo:session): session closed for user root May 27 10:11:09 volumio volumio[2785]: error: MPD error: The expression evaluated to a falsy value: May 27 10:11:09 volumio volumio[2785]: assert.ok(self.idling) May 27 10:11:09 volumio volumio[2785]: error: The expression evaluated to a falsy value: May 27 10:11:09 volumio volumio[2785]: assert.ok(self.idling) May 27 10:11:09 volumio volumio[2785]: error: updateQueue error: null May 27 10:11:10 volumio volumio[2785]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 10:11:10 volumio volumio[2785]: TypeError: Cannot read property 'off' of undefined May 27 10:11:10 volumio volumio[2785]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 27 10:11:10 volumio volumio[2785]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 27 10:11:10 volumio volumio[2785]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 27 10:11:10 volumio volumio[2785]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 27 10:11:10 volumio volumio[2785]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 27 10:11:10 volumio volumio[2785]: at Socket.emit (events.js:400:28) May 27 10:11:10 volumio volumio[2785]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 27 10:11:10 volumio volumio[2785]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 27 10:11:10 volumio volumio[2785]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 10:11:10 volumio sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-27 10:10 May 27 10:11:10 volumio sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"