-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-10-14 11:35:33 CEST. -- Oct 14 11:34:00 timmari volumio[1194]: info: Building wheels for collected packages: camilladsp Oct 14 11:34:00 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:00.003+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:00 timmari volumio[1194]: info: Running setup.py bdist_wheel for camilladsp: started Oct 14 11:34:00 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:00.010+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:00 timmari volumio[1194]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Oct 14 11:34:00 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:00.833+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:00 timmari volumio[1194]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-4eaokfuv/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Oct 14 11:34:00 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:00.840+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:00 timmari volumio[1194]: info: Successfully built camilladsp Oct 14 11:34:00 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:00.855+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:01 timmari volumio[1194]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Oct 14 11:34:01 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:01.089+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:01 timmari volumio[1194]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Oct 14 11:34:01 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:01.365+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:04 timmari volumio[1194]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Oct 14 11:34:04 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:04.870+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:04 timmari volumio[1194]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Oct 14 11:34:04 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:04.879+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:04 timmari volumio[1194]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-1tkbyu57 Oct 14 11:34:04 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:04.883+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:07 timmari volumio[1194]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Oct 14 11:34:07 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:07.294+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:07 timmari volumio[1194]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Oct 14 11:34:07 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:07.325+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:08 timmari volumio[1194]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Oct 14 11:34:08 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:08.765+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:09 timmari volumio[1194]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Oct 14 11:34:09 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:09.086+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:09 timmari volumio[1194]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Oct 14 11:34:09 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:09.442+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:09 timmari volumio[1194]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Oct 14 11:34:09 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:09.529+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:10 timmari volumio[1194]: info: Downloading https://www.piwheels.org/simple/pyrsistent/pyrsistent-0.19.3-cp37-cp37m-linux_armv7l.whl (83kB) Oct 14 11:34:10 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:10.842+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:11 timmari volumio[1194]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Oct 14 11:34:11 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:11.241+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:11 timmari volumio[1194]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Oct 14 11:34:11 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:11.832+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:11 timmari volumio[1194]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Oct 14 11:34:11 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:11.945+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:12 timmari volumio[1194]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Oct 14 11:34:12 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:12.683+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:12 timmari volumio[1194]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Oct 14 11:34:12 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:12.876+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:13 timmari volumio[1194]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Oct 14 11:34:13 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:13.826+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:13 timmari volumio[1194]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Oct 14 11:34:13 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:13.967+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:14 timmari volumio[1194]: info: Collecting zipp>=3.1.0; python_version < "3.10" (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) Oct 14 11:34:14 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:14.024+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:14 timmari volumio[1194]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Oct 14 11:34:14 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:14.610+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:14 timmari volumio[1194]: info: Building wheels for collected packages: camilladsp-plot Oct 14 11:34:14 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:14.728+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:14 timmari volumio[1194]: info: Running setup.py bdist_wheel for camilladsp-plot: started Oct 14 11:34:14 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:14.737+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:15 timmari volumio[1194]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Oct 14 11:34:15 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:15.595+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:15 timmari volumio[1194]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-5cwig2gw/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Oct 14 11:34:15 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:15.604+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:15 timmari volumio[1194]: info: Successfully built camilladsp-plot Oct 14 11:34:15 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:15.626+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:16 timmari volumio[1194]: info: Installing collected packages: pkgutil-resolve-name, pyrsistent, typing-extensions, zipp, importlib-resources, importlib-metadata, jsonschema, camilladsp-plot Oct 14 11:34:16 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:16.093+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:16 timmari volumio[1194]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Oct 14 11:34:16 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:16.710+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:16 timmari volumio[1194]: info: copying hw detection script Oct 14 11:34:16 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:16.872+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:16 timmari volumio[1194]: info: Detected cpu architecture as armv7l Oct 14 11:34:16 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:16.885+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:19 timmari sudo[11670]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Oct 14 11:34:19 timmari sudo[11670]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:19 timmari sudo[11670]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:19 timmari sudo[11674]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Oct 14 11:34:19 timmari sudo[11674]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:19 timmari sudo[11674]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:19 timmari sudo[11676]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Oct 14 11:34:19 timmari sudo[11676]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:19 timmari volumio[1194]: info: Reading package lists... Oct 14 11:34:19 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:19.314+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:19 timmari volumio[1194]: info: Building dependency tree... Oct 14 11:34:19 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:19.773+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:19 timmari volumio[1194]: info: Reading state information... Oct 14 11:34:19 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:19.782+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:20 timmari volumio[1194]: info: The following NEW packages will be installed: Oct 14 11:34:20 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:20.595+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:20 timmari volumio[1194]: info: drc Oct 14 11:34:20 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:20.613+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:31 timmari volumio[1194]: info: 0 upgraded, 1 newly installed, 0 to remove and 56 not upgraded. Oct 14 11:34:31 timmari volumio[1194]: info: Need to get 112 kB of archives. Oct 14 11:34:31 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:31.498+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:31 timmari volumio[1194]: info: After this operation, 512 kB of additional disk space will be used. Oct 14 11:34:31 timmari volumio[1194]: info: Get:1 https://apt2.volumio.org/raspbian buster/main armhf drc armhf 3.2.2~dfsg0-2 [112 kB] Oct 14 11:34:31 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:31.504+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:31 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:31.504+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:31 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:31.505+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: info: Fetched 112 kB in 11s (10.2 kB/s) Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.432+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: info: Selecting previously unselected package drc. Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.503+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: [144B blob data] Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.524+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: [146B blob data] Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.529+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: [151B blob data] Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.541+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: [150B blob data] Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.545+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: info: dpkg: warning: files list file for package 'bluetooth' missing; assuming package has no files currently installed Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.559+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: [520B blob data] Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.604+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.613+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:32 timmari volumio[1194]: info: Unpacking drc (3.2.2~dfsg0-2) ... Oct 14 11:34:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:32.623+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:33 timmari volumio[1194]: info: Setting up drc (3.2.2~dfsg0-2) ... Oct 14 11:34:33 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:33.272+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:33 timmari volumio[1194]: info: Processing triggers for man-db (2.8.5-2+deb10u1) ... Oct 14 11:34:33 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:33.296+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:33 timmari sudo[11676]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:33 timmari sudo[11018]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:33 timmari volumio[1194]: Plugin install end detected on script Oct 14 11:34:33 timmari volumio[1194]: info: Install script completed Oct 14 11:34:33 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:33.960+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:33 timmari volumio[1194]: info: Adding reference to registry Oct 14 11:34:33 timmari volumio5-onboarding[2096]: time=2026-10-14T11:34:33.965+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:34:33 timmari volumio[1194]: info: Done installing plugin. Oct 14 11:34:33 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:34:33 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 11:34:33 timmari volumio[1194]: info: Folder /tmp/plugins removed Oct 14 11:34:33 timmari volumio[1194]: info: Folder /tmp/downloaded_plugin.zip removed Oct 14 11:34:33 timmari volumio[1194]: info: Folder /data/temp removed Oct 14 11:34:40 timmari volumio[1194]: info: Enabling plugin fusiondsp Oct 14 11:34:40 timmari volumio[1194]: info: Loading plugin "fusiondsp"... Oct 14 11:34:41 timmari volumio[1194]: info: Preparing to generate the ALSA configuration file Oct 14 11:34:41 timmari volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 14 11:34:41 timmari volumio[1194]: info: Reading ALSA contributions from plugins. Oct 14 11:34:41 timmari volumio[1194]: info: Asound.conf file written Oct 14 11:34:41 timmari sudo[11819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 14 11:34:41 timmari sudo[11819]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari sudo[11819]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari volumio[1194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Oct 14 11:34:41 timmari volumio[1194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Oct 14 11:34:41 timmari volumio[1194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Oct 14 11:34:41 timmari volumio[1194]: info: Output device has changed, restarting MPD Oct 14 11:34:41 timmari sudo[11827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 11:34:41 timmari sudo[11827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari sudo[11827]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari volumio[1194]: info: Output device has changed, restarting Shairport Sync Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:41 timmari sudo[11830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 11:34:41 timmari sudo[11830]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari systemd[1]: Stopping Music Player Daemon... Oct 14 11:34:41 timmari volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 11:34:41 timmari volumio[1194]: info: PLUGIN START: fusiondsp Oct 14 11:34:41 timmari volumio[1194]: info: Loading i18n strings for locale it Oct 14 11:34:41 timmari volumio[1194]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 14 11:34:41 timmari systemd[1]: mpd.service: Succeeded. Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: FusionDsp - mixtype--------------------- Hardware Oct 14 11:34:41 timmari systemd[1]: Stopped Music Player Daemon. Oct 14 11:34:41 timmari volumio[1194]: info: Preparing to generate the ALSA configuration file Oct 14 11:34:41 timmari volumio[1194]: info: Done. Oct 14 11:34:41 timmari volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 14 11:34:41 timmari volumio[1194]: info: Reading ALSA contributions from plugins. Oct 14 11:34:41 timmari volumio[1194]: info: MPD Permissions set Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: Starting Shairport Sync Oct 14 11:34:41 timmari volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Oct 14 11:34:41 timmari volumio[1194]: info: Output device has changed, restarting MPD Oct 14 11:34:41 timmari systemd[1]: Starting Music Player Daemon... Oct 14 11:34:41 timmari sudo[11841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 11:34:41 timmari sudo[11841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 11:34:41 timmari sudo[11844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 11:34:41 timmari sudo[11844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari systemd[1]: shairport-sync.service: Succeeded. Oct 14 11:34:41 timmari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:41 timmari sudo[11844]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari volumio[1194]: info: Output device has changed, restarting Shairport Sync Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:41 timmari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:41 timmari sudo[11841]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari sudo[11847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 11:34:41 timmari sudo[11847]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari sudo[11835]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 11:34:41 timmari sudo[11835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari sudo[11835]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 14 11:34:41 timmari systemd[1]: mpd.service: Succeeded. Oct 14 11:34:41 timmari volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 11:34:41 timmari systemd[1]: Stopped Music Player Daemon. Oct 14 11:34:41 timmari volumio[1194]: error: Upnp client error: Error: This socket has been ended by the other party Oct 14 11:34:41 timmari systemd[1]: Starting Music Player Daemon... Oct 14 11:34:41 timmari volumio[1194]: info: MPD Permissions set Oct 14 11:34:41 timmari volumio[1194]: info: Shairport-Sync Started Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:41 timmari volumio[1194]: info: Starting Shairport Sync Oct 14 11:34:41 timmari sudo[11863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 11:34:41 timmari sudo[11863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari sudo[11856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 11:34:41 timmari sudo[11856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:41 timmari sudo[11856]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 11:34:41 timmari systemd[1]: shairport-sync.service: Succeeded. Oct 14 11:34:41 timmari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:41 timmari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:41 timmari sudo[11863]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:41 timmari volumio[1194]: info: Shairport-Sync Started Oct 14 11:34:42 timmari mpd[11866]: Oct 14 11:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 11:34:42 timmari systemd[1]: Started Music Player Daemon. Oct 14 11:34:42 timmari sudo[11830]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:42 timmari sudo[11847]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:42 timmari volumio[1194]: error: MPD error: The expression evaluated to a falsy value: Oct 14 11:34:42 timmari volumio[1194]: assert.ok(self.idling) Oct 14 11:34:42 timmari volumio[1194]: error: The expression evaluated to a falsy value: Oct 14 11:34:42 timmari volumio[1194]: assert.ok(self.idling) Oct 14 11:34:42 timmari volumio[1194]: error: updateQueue error: null Oct 14 11:34:43 timmari volumio[1194]: info: camilladsp service started and running in background, instance 1 Oct 14 11:34:43 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:43 timmari volumio[1194]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Oct 14 11:34:43 timmari volumio[1194]: info: FusionDsp loaded Oct 14 11:34:43 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:34:43 timmari sudo[11884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 14 11:34:43 timmari sudo[11884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:43 timmari volumio[1194]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 14 11:34:43 timmari volumio[1194]: info: Adding Signal Path Element [object Object] Oct 14 11:34:43 timmari volumio[1194]: info: Adding fusiondspeq DSP Signal Path Element Oct 14 11:34:43 timmari volumio[1194]: info: FusionDsp - ---- installed callbackRead Oct 14 11:34:43 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:34:43 timmari volumio[1194]: error: FusionDsp - WebSocket error: [object Object] Oct 14 11:34:43 timmari volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 14 11:34:43 timmari systemd[1]: Started FusionDsp Daemon. Oct 14 11:34:43 timmari sudo[11884]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:44 timmari volumio[1194]: 0+0 records in Oct 14 11:34:44 timmari volumio[1194]: 0+0 records out Oct 14 11:34:44 timmari volumio[1194]: 0 bytes copied, 0.000235364 s, 0.0 kB/s Oct 14 11:34:47 timmari volumio[1194]: info: Enabling plugin fusiondsp Oct 14 11:34:47 timmari volumio[1194]: info: Loading plugin "fusiondsp"... Oct 14 11:34:47 timmari volumio[1194]: info: Applying required configuration parameters for plugin fusiondsp Oct 14 11:34:47 timmari volumio[1194]: info: Preparing to generate the ALSA configuration file Oct 14 11:34:47 timmari volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 14 11:34:47 timmari volumio[1194]: info: Reading ALSA contributions from plugins. Oct 14 11:34:47 timmari volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Oct 14 11:34:47 timmari volumio[1194]: info: Output device has changed, restarting MPD Oct 14 11:34:47 timmari sudo[11907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 11:34:47 timmari sudo[11907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:47 timmari sudo[11907]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:47 timmari volumio[1194]: info: Output device has changed, restarting Shairport Sync Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:47 timmari sudo[11910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 11:34:47 timmari sudo[11910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:47 timmari systemd[1]: Stopping Music Player Daemon... Oct 14 11:34:47 timmari volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 11:34:47 timmari volumio[1194]: info: PLUGIN START: fusiondsp Oct 14 11:34:47 timmari systemd[1]: mpd.service: Succeeded. Oct 14 11:34:47 timmari systemd[1]: Stopped Music Player Daemon. Oct 14 11:34:47 timmari volumio[1194]: info: Loading i18n strings for locale it Oct 14 11:34:47 timmari volumio[1194]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: FusionDsp - mixtype--------------------- Hardware Oct 14 11:34:47 timmari volumio[1194]: info: Preparing to generate the ALSA configuration file Oct 14 11:34:47 timmari volumio[1194]: info: Done. Oct 14 11:34:47 timmari volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 14 11:34:47 timmari volumio[1194]: info: Reading ALSA contributions from plugins. Oct 14 11:34:47 timmari volumio[1194]: info: MPD Permissions set Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari systemd[1]: Starting Music Player Daemon... Oct 14 11:34:47 timmari volumio[1194]: info: Starting Shairport Sync Oct 14 11:34:47 timmari volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Oct 14 11:34:47 timmari volumio[1194]: info: Output device has changed, restarting MPD Oct 14 11:34:47 timmari sudo[11921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 11:34:47 timmari sudo[11921]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:47 timmari sudo[11924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 11:34:47 timmari sudo[11924]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:47 timmari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 11:34:47 timmari systemd[1]: shairport-sync.service: Succeeded. Oct 14 11:34:47 timmari sudo[11924]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:47 timmari sudo[11915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 11:34:47 timmari sudo[11915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:47 timmari volumio[1194]: info: Output device has changed, restarting Shairport Sync Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:47 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:47 timmari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:47 timmari sudo[11915]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:47 timmari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:47 timmari sudo[11927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 11:34:47 timmari sudo[11927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:47 timmari sudo[11921]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:48 timmari systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 14 11:34:48 timmari systemd[1]: mpd.service: Succeeded. Oct 14 11:34:48 timmari systemd[1]: Stopped Music Player Daemon. Oct 14 11:34:48 timmari systemd[1]: Starting Music Player Daemon... Oct 14 11:34:48 timmari volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 11:34:48 timmari volumio[1194]: info: MPD Permissions set Oct 14 11:34:48 timmari volumio[1194]: info: Shairport-Sync Started Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:48 timmari volumio[1194]: info: Starting Shairport Sync Oct 14 11:34:48 timmari sudo[11937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 11:34:48 timmari sudo[11937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:48 timmari sudo[11937]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:48 timmari sudo[11944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 11:34:48 timmari sudo[11944]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:48 timmari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 11:34:48 timmari systemd[1]: shairport-sync.service: Succeeded. Oct 14 11:34:48 timmari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:48 timmari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 11:34:48 timmari sudo[11944]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:48 timmari volumio[1194]: info: Shairport-Sync Started Oct 14 11:34:48 timmari volumio[1194]: error: Upnp client error: Error: This socket has been ended by the other party Oct 14 11:34:48 timmari mpd[11945]: Oct 14 11:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 11:34:48 timmari systemd[1]: Started Music Player Daemon. Oct 14 11:34:48 timmari sudo[11910]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:48 timmari sudo[11927]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:48 timmari volumio[1194]: error: MPD error: The expression evaluated to a falsy value: Oct 14 11:34:48 timmari volumio[1194]: assert.ok(self.idling) Oct 14 11:34:48 timmari volumio[1194]: error: The expression evaluated to a falsy value: Oct 14 11:34:48 timmari volumio[1194]: assert.ok(self.idling) Oct 14 11:34:48 timmari volumio[1194]: error: updateQueue error: null Oct 14 11:34:49 timmari volumio[1194]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 14 11:34:49 timmari volumio[1194]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Oct 14 11:34:49 timmari volumio[1194]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 14 11:34:49 timmari volumio[1194]: info: camilladsp service started and running in background, instance 2 Oct 14 11:34:49 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:34:49 timmari volumio[1194]: cannot open device 'volumioHw': Device or resource busy Oct 14 11:34:49 timmari volumio[1194]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 14 11:34:49 timmari volumio[1194]: cannot open device 'volumioHw': Device or resource busy Oct 14 11:34:49 timmari volumio[1194]: info: FusionDsp loaded Oct 14 11:34:49 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:34:49 timmari sudo[11982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 14 11:34:49 timmari volumio[1194]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 14 11:34:49 timmari volumio[1194]: info: Adding Signal Path Element [object Object] Oct 14 11:34:49 timmari volumio[1194]: info: Updating fusiondspeq DSP Signal Path Element Oct 14 11:34:49 timmari volumio[1194]: info: FusionDsp - ---- installed callbackRead Oct 14 11:34:49 timmari sudo[11982]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:34:49 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:34:49 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: Oct 14 11:34:49 timmari volumio[1194]: error: FusionDsp - invalid sample rate Oct 14 11:34:49 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: Oct 14 11:34:49 timmari volumio[1194]: error: FusionDsp - invalid sample rate Oct 14 11:34:49 timmari sudo[11982]: pam_unix(sudo:session): session closed for user root Oct 14 11:34:50 timmari volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 14 11:34:50 timmari volumio[1194]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 14 11:35:08 timmari volumio[1194]: info: CoreCommandRouter::volumioStop Oct 14 11:35:08 timmari volumio[1194]: info: CoreStateMachine::stop Oct 14 11:35:08 timmari volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:35:08 timmari volumio[1194]: info: CoreStateMachine::stPlaybackTimer Oct 14 11:35:08 timmari volumio[1194]: info: CoreStateMachine::updateTrackBlock Oct 14 11:35:08 timmari volumio[1194]: info: CorePlayQueue::getTrackBlock Oct 14 11:35:08 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:08 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:08 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:35:08 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:08 timmari volumio[1194]: info: CoreStateMachine::serviceStop Oct 14 11:35:08 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:08 timmari volumio[1194]: info: CoreCommandRouter::serviceStop Oct 14 11:35:08 timmari volumio[1194]: info: [1791970508639] ControllerWebradio::stop Oct 14 11:35:08 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:35:08 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status stop Oct 14 11:35:08 timmari volumio[1194]: info: sendMpdCommand stop took 53 milliseconds Oct 14 11:35:08 timmari sudo[15191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 14 11:35:08 timmari sudo[15191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:08 timmari sudo[15191]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:08 timmari volumio[1194]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 14 11:35:08 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Oct 14 11:35:08 timmari volumio[1194]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Oct 14 11:35:14 timmari volumio[1194]: info: CoreCommandRouter::volumioPlay Oct 14 11:35:14 timmari volumio[1194]: info: CoreStateMachine::play index undefined Oct 14 11:35:14 timmari volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 11:35:14 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:14 timmari volumio[1194]: info: CoreStateMachine::startPlaybackTimer Oct 14 11:35:14 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:14 timmari volumio[1194]: info: [1791970514296] ControllerWebradio::clearAddPlayTrack Oct 14 11:35:14 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 11:35:14 timmari volumio[1194]: info: sendMpdCommand stop took 1 milliseconds Oct 14 11:35:14 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 11:35:14 timmari volumio[1194]: info: Oct 14 11:35:14 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:14 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:14 timmari volumio[1194]: info: sendMpdCommand clear took 1 milliseconds Oct 14 11:35:14 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1528122" Oct 14 11:35:14 timmari volumio[1194]: info: Oct 14 11:35:14 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:14 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:14 timmari volumio[1194]: error: updateQueue error: null Oct 14 11:35:14 timmari volumio[1194]: info: ------------------------------ 3ms Oct 14 11:35:14 timmari volumio[1194]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1528122" took 626 milliseconds Oct 14 11:35:14 timmari volumio[1194]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 11:35:14 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand play Oct 14 11:35:14 timmari volumio[1194]: info: Oct 14 11:35:14 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:14 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:14 timmari volumio[1194]: info: ------------------------------ 627ms Oct 14 11:35:14 timmari volumio[1194]: info: Oct 14 11:35:14 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:14 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:14 timmari volumio[1194]: info: sendMpdCommand play took 3 milliseconds Oct 14 11:35:14 timmari volumio[1194]: info: ------------------------------ 2ms Oct 14 11:35:14 timmari volumio[1194]: info: ------------------------------ 2ms Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:18 timmari volumio[1194]: info: camilladsp stopping service pid 11891... Oct 14 11:35:18 timmari volumio[1194]: info: camilladsp service terminated, instance 1 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:18 timmari volumio[1194]: info: camilladsp service started and running in background, instance 1 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:18 timmari volumio[1194]: info: camilladsp stopping service pid 16811... Oct 14 11:35:18 timmari volumio[1194]: info: camilladsp service terminated, instance 2 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:18 timmari volumio[1194]: info: camilladsp service started and running in background, instance 2 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:18 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:18 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 79 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 78 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:18 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 7 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 8ms Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 7 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 6 milliseconds Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:18 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:18 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:18 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus stop Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:18 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:18 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:18 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:35:18 timmari volumio[1194]: info: Received an update from plugin. extracting info from payload Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 104ms Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 110ms Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces system playlist update Oct 14 11:35:18 timmari volumio[1194]: info: Ignoring MPD Status Update Oct 14 11:35:18 timmari volumio[1194]: info: Oct 14 11:35:18 timmari volumio[1194]: ---------------------------- MPD announces state update: player Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::getState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Oct 14 11:35:18 timmari volumio[1194]: error: FusionDsp - WebSocket error: [object Object] Oct 14 11:35:18 timmari volumio[1194]: error: FusionDsp - WebSocket error: [object Object] Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 87ms Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 87 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 86 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 85 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 85 milliseconds Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 62ms Oct 14 11:35:18 timmari volumio[1194]: info: sendMpdCommand status took 62 milliseconds Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::parseState Oct 14 11:35:18 timmari volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:18 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:18 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:18 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:35:18 timmari volumio[1194]: info: Received an update from plugin. extracting info from payload Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:18 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:18 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:18 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:35:18 timmari volumio[1194]: info: Received an update from plugin. extracting info from payload Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:18 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:18 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:18 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:35:18 timmari volumio[1194]: info: Received an update from plugin. extracting info from payload Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:18 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 154ms Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 155ms Oct 14 11:35:18 timmari volumio[1194]: info: ------------------------------ 153ms Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:18 timmari sudo[16839]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16839]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:18 timmari sudo[16842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:18 timmari sudo[16842]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16842]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:18 timmari sudo[16845]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:18 timmari sudo[16847]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:18 timmari sudo[16845]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:18 timmari sudo[16847]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:18 timmari sudo[16850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:18 timmari sudo[16850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:18 timmari sudo[16854]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:18 timmari sudo[16850]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:18 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:18 timmari sudo[16854]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari sudo[16857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari sudo[16857]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:19 timmari sudo[16860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari sudo[16860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:19 timmari sudo[16857]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 399 milliseconds Oct 14 11:35:19 timmari volumio[1194]: info: sendMpdCommand playlistinfo took 398 milliseconds Oct 14 11:35:19 timmari volumio[1194]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:35:19 timmari volumio[1194]: verbose: ControllerMpd::parseTrackInfo Oct 14 11:35:19 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:19 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:19 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:19 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:35:19 timmari volumio[1194]: info: Received an update from plugin. extracting info from payload Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:19 timmari sudo[16860]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari volumio[1194]: info: ControllerMpd::pushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::servicePushState Oct 14 11:35:19 timmari volumio[1194]: info: CorePlayQueue::getTrack 0 Oct 14 11:35:19 timmari volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Art Pepper - Yardbird Suite","artist":"Jazz Groove Mix 2 (Free FLAC)","album":null,"uri":"http://streams.radiomast.io:80/8a384ff3-6fd1-4e5d-b47d-0cbefeffe8d7","trackType":""} Oct 14 11:35:19 timmari volumio[1194]: verbose: CURRENT POSITION 0 Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::syncState stateService play Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::syncState currentStatus play Oct 14 11:35:19 timmari volumio[1194]: info: Received an update from plugin. extracting info from payload Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreStateMachine::pushState Oct 14 11:35:19 timmari volumio[1194]: info: CoreCommandRouter::volumioPushState Oct 14 11:35:19 timmari volumio[1194]: info: ------------------------------ 518ms Oct 14 11:35:19 timmari volumio[1194]: info: ------------------------------ 493ms Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:19 timmari sudo[16877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari sudo[16877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:19 timmari sudo[16880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari sudo[16880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:19 timmari sudo[16877]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari sudo[16880]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum status play Oct 14 11:35:19 timmari sudo[16883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari sudo[16883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:19 timmari sudo[16883]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari sudo[16886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari sudo[16886]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:19 timmari sudo[16886]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:19 timmari volumio[1194]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 14 11:35:19 timmari volumio[1194]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Oct 14 11:35:25 timmari volumio[1194]: info: Enabling plugin fusiondsp Oct 14 11:35:25 timmari volumio[1194]: info: Loading plugin "fusiondsp"... Oct 14 11:35:25 timmari volumio[1194]: info: Applying required configuration parameters for plugin fusiondsp Oct 14 11:35:25 timmari volumio[1194]: info: Preparing to generate the ALSA configuration file Oct 14 11:35:25 timmari volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 14 11:35:25 timmari volumio[1194]: info: Reading ALSA contributions from plugins. Oct 14 11:35:25 timmari volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Oct 14 11:35:25 timmari volumio[1194]: info: Output device has changed, restarting MPD Oct 14 11:35:25 timmari sudo[17903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 11:35:25 timmari sudo[17903]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari sudo[17903]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:25 timmari volumio[1194]: info: Output device has changed, restarting Shairport Sync Oct 14 11:35:25 timmari sudo[17906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 11:35:25 timmari sudo[17906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:35:25 timmari systemd[1]: Stopping Music Player Daemon... Oct 14 11:35:25 timmari systemd[1]: mpd.service: Succeeded. Oct 14 11:35:25 timmari systemd[1]: Stopped Music Player Daemon. Oct 14 11:35:25 timmari volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 11:35:25 timmari volumio[1194]: info: PLUGIN START: fusiondsp Oct 14 11:35:25 timmari volumio[1194]: info: Loading i18n strings for locale it Oct 14 11:35:25 timmari volumio[1194]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: FusionDsp - mixtype--------------------- Hardware Oct 14 11:35:25 timmari volumio[1194]: info: Preparing to generate the ALSA configuration file Oct 14 11:35:25 timmari volumio[1194]: info: Done. Oct 14 11:35:25 timmari volumio[1194]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 14 11:35:25 timmari volumio[1194]: info: Reading ALSA contributions from plugins. Oct 14 11:35:25 timmari volumio[1194]: info: MPD Permissions set Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari systemd[1]: Starting Music Player Daemon... Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: Starting Shairport Sync Oct 14 11:35:25 timmari volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Oct 14 11:35:25 timmari volumio[1194]: info: Output device has changed, restarting MPD Oct 14 11:35:25 timmari sudo[17911]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 11:35:25 timmari sudo[17911]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari sudo[17911]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:25 timmari sudo[17931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 11:35:25 timmari sudo[17931]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari sudo[17937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 11:35:25 timmari sudo[17937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 11:35:25 timmari sudo[17937]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:25 timmari systemd[1]: shairport-sync.service: Succeeded. Oct 14 11:35:25 timmari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 11:35:25 timmari volumio[1194]: info: Output device has changed, restarting Shairport Sync Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:35:25 timmari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 11:35:25 timmari sudo[17931]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:25 timmari sudo[17942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 11:35:25 timmari sudo[17942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 14 11:35:25 timmari systemd[1]: mpd.service: Succeeded. Oct 14 11:35:25 timmari systemd[1]: Stopped Music Player Daemon. Oct 14 11:35:25 timmari systemd[1]: Starting Music Player Daemon... Oct 14 11:35:25 timmari volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 11:35:25 timmari volumio[1194]: info: MPD Permissions set Oct 14 11:35:25 timmari volumio[1194]: info: Shairport-Sync Started Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari sudo[17948]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 11:35:25 timmari sudo[17948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 11:35:25 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:25 timmari sudo[17948]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:25 timmari volumio[1194]: info: Starting Shairport Sync Oct 14 11:35:25 timmari sudo[17970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 11:35:25 timmari sudo[17970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:25 timmari systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 11:35:25 timmari systemd[1]: shairport-sync.service: Succeeded. Oct 14 11:35:25 timmari systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 11:35:25 timmari systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 11:35:25 timmari sudo[17970]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:25 timmari volumio[1194]: info: Shairport-Sync Started Oct 14 11:35:26 timmari mpd[17961]: Oct 14 11:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 11:35:26 timmari systemd[1]: Started Music Player Daemon. Oct 14 11:35:26 timmari sudo[17906]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:26 timmari sudo[17942]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:26 timmari volumio[1194]: error: MPD error: The expression evaluated to a falsy value: Oct 14 11:35:26 timmari volumio[1194]: assert.ok(self.idling) Oct 14 11:35:26 timmari volumio[1194]: error: The expression evaluated to a falsy value: Oct 14 11:35:26 timmari volumio[1194]: assert.ok(self.idling) Oct 14 11:35:26 timmari volumio[1194]: error: updateQueue error: null Oct 14 11:35:27 timmari volumio[1194]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 14 11:35:27 timmari volumio[1194]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Oct 14 11:35:27 timmari volumio[1194]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 14 11:35:27 timmari volumio[1194]: info: camilladsp service started and running in background, instance 3 Oct 14 11:35:27 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 11:35:27 timmari volumio[1194]: cannot open device 'volumioHw': Device or resource busy Oct 14 11:35:27 timmari volumio[1194]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 14 11:35:27 timmari volumio[1194]: cannot open device 'volumioHw': Device or resource busy Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp loaded Oct 14 11:35:27 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 14 11:35:27 timmari volumio[1194]: info: Adding Signal Path Element [object Object] Oct 14 11:35:27 timmari volumio[1194]: info: Updating fusiondspeq DSP Signal Path Element Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- installed callbackRead Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:27 timmari sudo[18254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 14 11:35:27 timmari sudo[18254]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 14 11:35:27 timmari volumio[1194]: info: camilladsp stopping service pid 18244... Oct 14 11:35:27 timmari sudo[18254]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:27 timmari volumio[1194]: info: camilladsp service terminated, instance 3 Oct 14 11:35:27 timmari volumio[1194]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 14 11:35:27 timmari volumio[1194]: info: camilladsp service started and running in background, instance 3 Oct 14 11:35:27 timmari volumio[1194]: error: FusionDsp - WebSocket error: [object Object] Oct 14 11:35:27 timmari volumio[1194]: error: FusionDsp - WebSocket error: [object Object] Oct 14 11:35:27 timmari volumio[1194]: error: FusionDsp - WebSocket error: [object Object] Oct 14 11:35:27 timmari volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 14 11:35:27 timmari volumio[1194]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Oct 14 11:35:28 timmari volumio5-onboarding[2096]: time=2026-10-14T11:35:28.000+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:35:32 timmari volumio[1194]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Oct 14 11:35:32 timmari volumio[1194]: info: FusionDsp - Stopping FusionDsp service Oct 14 11:35:32 timmari volumio[1194]: info: camilladsp stopping service pid 19591... Oct 14 11:35:32 timmari volumio[1194]: info: camilladsp service terminated, instance 3 Oct 14 11:35:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:35:32.429+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:35:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:35:32.433+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:35:32 timmari sudo[19595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Oct 14 11:35:32 timmari sudo[19595]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 11:35:32 timmari systemd[1]: Stopping FusionDsp Daemon... Oct 14 11:35:32 timmari volumio[11886]: Backend configuration: Oct 14 11:35:32 timmari volumio[11886]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Oct 14 11:35:32 timmari volumio[11886]: active_config_txt: null Oct 14 11:35:32 timmari volumio[11886]: camilla_host: 127.0.0.1 Oct 14 11:35:32 timmari volumio[11886]: camilla_port: 9876 Oct 14 11:35:32 timmari volumio[11886]: coeff_dir: /data/INTERNAL/FusionDsp/filters Oct 14 11:35:32 timmari volumio[11886]: config_dir: /data/configuration/audio_interface/fusiondsp Oct 14 11:35:32 timmari volumio[11886]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Oct 14 11:35:32 timmari volumio[11886]: log_file: /tmp/camilladsp.log Oct 14 11:35:32 timmari volumio[11886]: on_get_active_config: null Oct 14 11:35:32 timmari volumio[11886]: on_set_active_config: null Oct 14 11:35:32 timmari volumio[11886]: port: 5011 Oct 14 11:35:32 timmari volumio[11886]: supported_capture_types: null Oct 14 11:35:32 timmari volumio[11886]: supported_playback_types: null Oct 14 11:35:32 timmari volumio[11886]: update_config_symlink: false Oct 14 11:35:32 timmari volumio[11886]: update_config_txt: false Oct 14 11:35:32 timmari volumio[11886]: ======== Running on http://0.0.0.0:5011 ======== Oct 14 11:35:32 timmari volumio[11886]: (Press CTRL+C to quit) Oct 14 11:35:32 timmari systemd[1]: fusiondsp.service: Succeeded. Oct 14 11:35:32 timmari systemd[1]: Stopped FusionDsp Daemon. Oct 14 11:35:32 timmari sudo[19595]: pam_unix(sudo:session): session closed for user root Oct 14 11:35:32 timmari volumio[1194]: info: FusionDsp - Reporting Fusion DSP Disabled Oct 14 11:35:32 timmari volumio[1194]: info: Removing fusiondspeq DSP Signal Path Element Oct 14 11:35:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:35:32.853+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:35:32 timmari volumio[1194]: info: Check plugin dependencies Oct 14 11:35:32 timmari volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 11:35:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:35:32.893+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:35:32 timmari volumio[1194]: info: Rename folder Oct 14 11:35:32 timmari volumio[1194]: info: Folder /tmp/downloaded_plugin.zip removed Oct 14 11:35:32 timmari volumio5-onboarding[2096]: time=2026-10-14T11:35:32.924+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:35:32 timmari volumio[1194]: info: Move to category Oct 14 11:35:32 timmari volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 11:35:33 timmari volumio[1194]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 14 11:35:33 timmari volumio[1194]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 14 11:35:33 timmari volumio[1194]: at onErrorNT (internal/child_process.js:465:16) Oct 14 11:35:33 timmari volumio[1194]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 14 11:35:33 timmari volumio[1194]: errno: -2, Oct 14 11:35:33 timmari volumio[1194]: code: 'ENOENT', Oct 14 11:35:33 timmari volumio[1194]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 14 11:35:33 timmari volumio[1194]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 14 11:35:33 timmari volumio[1194]: spawnargs: [ Oct 14 11:35:33 timmari volumio[1194]: '-p', Oct 14 11:35:33 timmari volumio[1194]: 9876, Oct 14 11:35:33 timmari volumio[1194]: '-o', Oct 14 11:35:33 timmari volumio[1194]: '/tmp/camilladsp.log', Oct 14 11:35:33 timmari volumio[1194]: '-l', Oct 14 11:35:33 timmari volumio[1194]: 'warn', Oct 14 11:35:33 timmari volumio[1194]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 14 11:35:33 timmari volumio[1194]: ] Oct 14 11:35:33 timmari volumio[1194]: } Oct 14 11:35:33 timmari volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 11:35:33 timmari sudo[19678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-14 11:34 Oct 14 11:35:33 timmari sudo[19678]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"