-- Logs begin at Fri 2025-05-16 07:50:54 EEST, end at Fri 2025-05-16 07:53:15 EEST. -- May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:00 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:00 volumio thd[619]: [158B blob data] May 16 07:52:00 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:00 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 May 16 07:52:00 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 78 May 16 07:52:00 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 77 May 16 07:52:00 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:00 volumio thd[619]: {"time":1747371119987,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:00 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:00 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:00 volumio volumio[881]: info: May 16 07:52:00 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:00 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:00 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:00 volumio thd[619]: [158B blob data] May 16 07:52:00 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:00 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 May 16 07:52:00 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 78 May 16 07:52:00 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 76 May 16 07:52:00 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:00 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 76 May 16 07:52:00 volumio thd[619]: {"time":1747371120255,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:00 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:00 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:00 volumio volumio[881]: info: May 16 07:52:00 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:00 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:00 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:00 volumio thd[619]: [158B blob data] May 16 07:52:00 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:00 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 May 16 07:52:00 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 76 May 16 07:52:00 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 75 May 16 07:52:00 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:00 volumio thd[619]: {"time":1747371120516,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:00 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:00 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:00 volumio volumio[881]: info: May 16 07:52:00 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:00 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:00 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:00 volumio thd[619]: [158B blob data] May 16 07:52:00 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:00 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 May 16 07:52:00 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 76 May 16 07:52:00 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 74 May 16 07:52:00 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:00 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 74 May 16 07:52:00 volumio thd[619]: {"time":1747371120747,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:00 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:00 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:00 volumio volumio[881]: info: May 16 07:52:00 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:00 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:00 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:00 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:01 volumio thd[619]: [158B blob data] May 16 07:52:01 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:01 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 May 16 07:52:01 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 74 May 16 07:52:01 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 73 May 16 07:52:01 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:01 volumio thd[619]: {"time":1747371120980,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:01 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:01 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:01 volumio volumio[881]: info: May 16 07:52:01 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:01 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:01 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:01 volumio thd[619]: [158B blob data] May 16 07:52:01 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:01 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 May 16 07:52:01 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 74 May 16 07:52:01 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 72 May 16 07:52:01 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:01 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 72 May 16 07:52:01 volumio thd[619]: {"time":1747371121274,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:01 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:01 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:01 volumio volumio[881]: info: May 16 07:52:01 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:01 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:01 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:01 volumio thd[619]: [158B blob data] May 16 07:52:01 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:01 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 16 07:52:01 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 72 May 16 07:52:01 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 71 May 16 07:52:01 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:01 volumio thd[619]: {"time":1747371121615,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:01 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:01 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:01 volumio volumio[881]: info: May 16 07:52:01 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:01 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:01 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:01 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:01 volumio thd[619]: [158B blob data] May 16 07:52:01 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:01 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 May 16 07:52:01 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 72 May 16 07:52:01 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 70 May 16 07:52:01 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:01 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 70 May 16 07:52:02 volumio thd[619]: {"time":1747371121942,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:02 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:02 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:02 volumio volumio[881]: info: May 16 07:52:02 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:02 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:02 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:02 volumio thd[619]: [158B blob data] May 16 07:52:02 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:02 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 May 16 07:52:02 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 70 May 16 07:52:02 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 69 May 16 07:52:02 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:02 volumio thd[619]: {"time":1747371122223,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:02 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:02 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:02 volumio volumio[881]: info: May 16 07:52:02 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:02 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:02 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:02 volumio thd[619]: [158B blob data] May 16 07:52:02 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:02 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 May 16 07:52:02 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 70 May 16 07:52:02 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 68 May 16 07:52:02 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:02 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 68 May 16 07:52:02 volumio thd[619]: {"time":1747371122587,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:02 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:02 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:02 volumio volumio[881]: info: May 16 07:52:02 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:02 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:02 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:02 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:02 volumio thd[619]: [158B blob data] May 16 07:52:02 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:02 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 May 16 07:52:02 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 68 May 16 07:52:02 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 67 May 16 07:52:02 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:04 volumio volumio[881]: SPOTIFY: SETTING SPOTIFY VOLUME 68 May 16 07:52:04 volumio volumio[881]: info: Sending Spotify command with payload to local API: /player/volume May 16 07:52:04 volumio go-librespot[1789]: time="2025-05-16T07:52:04+03:00" level=debug msg="update volume to 44563/65535" May 16 07:52:05 volumio go-librespot[1789]: time="2025-05-16T07:52:05+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 16 07:52:05 volumio go-librespot[1789]: time="2025-05-16T07:52:05+03:00" level=trace msg="emitting websocket event: volume" May 16 07:52:05 volumio volumio[881]: SPOTIFY: received: {"type":"volume","data":{"value":68,"max":100}} May 16 07:52:05 volumio volumio[881]: SPOTIFY: RECEIVED SPOTIFY VOLUME 68 May 16 07:52:05 volumio volumio[881]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 16 07:52:09 volumio volumio[881]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 16 07:52:09 volumio volumio[881]: info: Folder /tmp/plugins removed May 16 07:52:09 volumio volumio[881]: info: Check plugin dependencies May 16 07:52:09 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 07:52:09 volumio volumio[881]: info: Checking if plugin already exists May 16 07:52:09 volumio volumio[881]: info: Rename folder May 16 07:52:09 volumio volumio[881]: info: Folder /tmp/downloaded_plugin.zip removed May 16 07:52:09 volumio volumio[881]: info: Move to category May 16 07:52:09 volumio volumio[881]: info: Checking if install.sh is present May 16 07:52:09 volumio volumio[881]: info: Executing install.sh May 16 07:52:09 volumio sudo[2385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 16 07:52:09 volumio sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:09 volumio volumio[881]: info: copying demo flters May 16 07:52:09 volumio volumio[881]: info: ./presets/ May 16 07:52:09 volumio volumio[881]: info: ./presets/2XEQ15/ May 16 07:52:09 volumio sudo[2411]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 16 07:52:09 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:09 volumio volumio[881]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/PEQ/ May 16 07:52:09 volumio volumio[881]: info: ./presets/convfir/ May 16 07:52:09 volumio volumio[881]: info: ./presets/convfir/kef.json May 16 07:52:09 volumio volumio[881]: info: ./presets/convfir/test.json May 16 07:52:09 volumio volumio[881]: info: ./presets/EQ15/ May 16 07:52:09 volumio volumio[881]: info: ./presets/EQ15/.Bass-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/EQ15/.Classic-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/EQ15/.Voice-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: ./presets/EQ15/.Rock-FusionDsp.json May 16 07:52:09 volumio volumio[881]: info: Installing/fusiondsp dependencies May 16 07:52:09 volumio volumio[881]: info: Hit:1 http://deb.debian.org/debian buster InRelease May 16 07:52:10 volumio volumio[881]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease May 16 07:52:12 volumio volumio[881]: info: Reading package lists... May 16 07:52:12 volumio volumio[881]: info: Building dependency tree... May 16 07:52:12 volumio volumio[881]: info: Reading state information... May 16 07:52:12 volumio volumio[881]: info: All packages are up to date. May 16 07:52:12 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root May 16 07:52:12 volumio sudo[2634]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 16 07:52:12 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:12 volumio volumio[881]: info: Reading package lists... May 16 07:52:13 volumio volumio[881]: info: Building dependency tree... May 16 07:52:13 volumio volumio[881]: info: Reading state information... May 16 07:52:13 volumio volumio[881]: info: python3-pip is already the newest version (18.1-5). May 16 07:52:13 volumio volumio[881]: info: You might want to run 'apt --fix-broken install' to correct these. May 16 07:52:13 volumio volumio[881]: info: The following packages have unmet dependencies: May 16 07:52:13 volumio volumio[881]: info: google-chrome-stable : Depends: fonts-liberation but it is not going to be installed May 16 07:52:13 volumio volumio[881]: info: Depends: libvulkan1 but it is not going to be installed May 16 07:52:13 volumio volumio[881]: info: python3-aiohttp : Depends: python3-yarl (>= 1.0) but it is not going to be installed May 16 07:52:13 volumio volumio[881]: info: Depends: python3-async-timeout but it is not going to be installed May 16 07:52:13 volumio volumio[881]: info: Depends: python3-attr but it is not going to be installed May 16 07:52:13 volumio volumio[881]: info: Depends: python3-multidict but it is not going to be installed May 16 07:52:13 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root May 16 07:52:13 volumio sudo[2638]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 16 07:52:13 volumio sudo[2638]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:13 volumio volumio[881]: info: lib/systemd/system/fusiondsp.service May 16 07:52:13 volumio sudo[2638]: pam_unix(sudo:session): session closed for user root May 16 07:52:16 volumio volumio[881]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 16 07:52:16 volumio volumio[881]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 16 07:52:16 volumio volumio[881]: info: May 16 07:52:16 volumio volumio[881]: info: cgui-1.0.0.zip opened May 16 07:52:16 volumio sudo[2642]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 16 07:52:16 volumio sudo[2642]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:16 volumio sudo[2642]: pam_unix(sudo:session): session closed for user root May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/LICENSE.txt May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/README.md May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/build/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/robots.txt May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/logo512.png May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/asset-manifest.json May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/index.html May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/.put_statics_here May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/favicon.ico May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/logo192.png May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 16 07:52:16 volumio sudo[2644]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 16 07:52:16 volumio sudo[2644]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/manifest.json May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/css-variables.css May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/service-worker.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 16 07:52:16 volumio sudo[2644]: pam_unix(sudo:session): session closed for user root May 16 07:52:16 volumio sudo[2646]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 16 07:52:16 volumio sudo[2646]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/build/static/ May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/build/static/css/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 16 07:52:16 volumio sudo[2646]: pam_unix(sudo:session): session closed for user root May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/build/static/media/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/build/static/js/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.71e11955.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/config/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/config/camillagui.yml May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/config/gui-config.yml May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/backend/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/filters_test.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/version.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/filters.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/settings.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/views.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/filterdefaults.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__init__.py May 16 07:52:16 volumio volumio[881]: info: creating directory: cgui/backend/__pycache__/ May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/routes.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/backend/filemanagement.py May 16 07:52:16 volumio volumio[881]: info: extracting: cgui/main.py May 16 07:52:17 volumio volumio[881]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 May 16 07:52:17 volumio volumio[881]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-odi9jpln May 16 07:52:19 volumio volumio[881]: info: Collecting PyYAML (from camilladsp==1.0.0) May 16 07:52:20 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) May 16 07:52:20 volumio volumio[881]: info: Installing build dependencies: started May 16 07:52:22 volumio go-librespot[1789]: time="2025-05-16T07:52:22+03:00" level=trace msg="sent dealer ping" May 16 07:52:22 volumio go-librespot[1789]: time="2025-05-16T07:52:22+03:00" level=trace msg="received dealer pong" May 16 07:52:26 volumio volumio[881]: info: Installing build dependencies: finished with status 'done' May 16 07:52:27 volumio thd[619]: {"time":1747371122940,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:27 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:27 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:27 volumio volumio[881]: info: May 16 07:52:27 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:27 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:27 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:27 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:27 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:27 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:27 volumio thd[619]: [158B blob data] May 16 07:52:27 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:27 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 May 16 07:52:27 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 68 May 16 07:52:27 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 66 May 16 07:52:27 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:27 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 66 May 16 07:52:27 volumio volumio[881]: info: Collecting websocket_client (from camilladsp==1.0.0) May 16 07:52:27 volumio thd[619]: {"time":1747371147428,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:27 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:27 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:27 volumio volumio[881]: info: May 16 07:52:27 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:27 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:27 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:27 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:27 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:27 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:27 volumio thd[619]: [158B blob data] May 16 07:52:27 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:27 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 May 16 07:52:27 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 66 May 16 07:52:27 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 65 May 16 07:52:27 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:27 volumio nmbd[804]: [2025/05/16 07:52:27.979403, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 16 07:52:27 volumio nmbd[804]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 16 07:52:27 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) May 16 07:52:28 volumio volumio[881]: info: Building wheels for collected packages: camilladsp, PyYAML May 16 07:52:28 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp: started May 16 07:52:28 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' May 16 07:52:28 volumio volumio[881]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-fbaw9_hx/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 May 16 07:52:28 volumio volumio[881]: info: Running setup.py bdist_wheel for PyYAML: started May 16 07:52:28 volumio volumio[881]: info: Running setup.py bdist_wheel for PyYAML: finished with status 'done' May 16 07:52:28 volumio volumio[881]: info: Stored in directory: /root/.cache/pip/wheels/e6/c6/ef/4e8ee93f1b79fc90562f1600d47189799f8213023d9dadafa2 May 16 07:52:28 volumio volumio[881]: info: Successfully built camilladsp PyYAML May 16 07:52:28 volumio volumio[881]: SPOTIFY: SETTING SPOTIFY VOLUME 66 May 16 07:52:28 volumio volumio[881]: info: Sending Spotify command with payload to local API: /player/volume May 16 07:52:28 volumio go-librespot[1789]: time="2025-05-16T07:52:28+03:00" level=debug msg="update volume to 43253/65535" May 16 07:52:28 volumio volumio[881]: info: Installing collected packages: PyYAML, websocket-client, camilladsp May 16 07:52:29 volumio volumio[881]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 May 16 07:52:29 volumio thd[619]: {"time":1747371147920,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:29 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:29 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:29 volumio volumio[881]: info: May 16 07:52:29 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:29 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:29 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:29 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:29 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:29 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:29 volumio thd[619]: [158B blob data] May 16 07:52:29 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:29 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 16 07:52:29 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 66 May 16 07:52:29 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 64 May 16 07:52:29 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:29 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 64 May 16 07:52:29 volumio thd[619]: {"time":1747371149356,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:29 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:29 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:29 volumio volumio[881]: info: May 16 07:52:29 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:29 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:29 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:29 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:29 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:29 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:29 volumio thd[619]: [158B blob data] May 16 07:52:29 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:29 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 May 16 07:52:29 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 64 May 16 07:52:29 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 63 May 16 07:52:29 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:30 volumio go-librespot[1789]: time="2025-05-16T07:52:30+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 16 07:52:30 volumio go-librespot[1789]: time="2025-05-16T07:52:30+03:00" level=trace msg="emitting websocket event: volume" May 16 07:52:30 volumio volumio[881]: SPOTIFY: received: {"type":"volume","data":{"value":66,"max":100}} May 16 07:52:30 volumio volumio[881]: SPOTIFY: RECEIVED SPOTIFY VOLUME 66 May 16 07:52:30 volumio volumio[881]: info: Setting Volumio Volume from Spotify: 66 May 16 07:52:30 volumio volumio[881]: info: VolumeController::SetAlsaVolume66 May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:30 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:30 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:30 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 66 May 16 07:52:30 volumio volumio[881]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 May 16 07:52:30 volumio volumio[881]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-vz31tky7 May 16 07:52:30 volumio thd[619]: {"time":1747371149950,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:30 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:30 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:30 volumio volumio[881]: info: May 16 07:52:30 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:30 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:30 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:30 volumio thd[619]: [158B blob data] May 16 07:52:30 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:30 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 May 16 07:52:30 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 66 May 16 07:52:30 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 65 May 16 07:52:30 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:30 volumio thd[619]: {"time":1747371150311,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:30 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:30 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:30 volumio volumio[881]: info: May 16 07:52:30 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:30 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:30 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:30 volumio thd[619]: [158B blob data] May 16 07:52:30 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:30 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 16 07:52:30 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 66 May 16 07:52:30 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 64 May 16 07:52:30 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:30 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 64 May 16 07:52:30 volumio thd[619]: {"time":1747371150581,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:30 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:30 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:30 volumio volumio[881]: info: May 16 07:52:30 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:30 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:30 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:30 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:30 volumio thd[619]: [158B blob data] May 16 07:52:30 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:30 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 May 16 07:52:30 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 64 May 16 07:52:30 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 63 May 16 07:52:30 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:31 volumio volumio[881]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) May 16 07:52:31 volumio volumio[881]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) May 16 07:52:31 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) May 16 07:52:31 volumio volumio[881]: info: Collecting attrs>=17.4.0 (from jsonschema->camilladsp-plot==1.0.2) May 16 07:52:32 volumio volumio[881]: SPOTIFY: SETTING SPOTIFY VOLUME 64 May 16 07:52:32 volumio volumio[881]: info: Sending Spotify command with payload to local API: /player/volume May 16 07:52:32 volumio go-librespot[1789]: time="2025-05-16T07:52:32+03:00" level=debug msg="update volume to 41942/65535" May 16 07:52:32 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/6a/21/5b6702a7f963e95456c0de2d495f67bf5fd62840ac655dc451586d23d39a/attrs-24.2.0-py3-none-any.whl (63kB) May 16 07:52:32 volumio volumio[881]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) May 16 07:52:32 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) May 16 07:52:32 volumio volumio[881]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) May 16 07:52:32 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl May 16 07:52:32 volumio volumio[881]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) May 16 07:52:33 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl May 16 07:52:33 volumio volumio[881]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) May 16 07:52:33 volumio go-librespot[1789]: time="2025-05-16T07:52:33+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 16 07:52:33 volumio go-librespot[1789]: time="2025-05-16T07:52:33+03:00" level=trace msg="emitting websocket event: volume" May 16 07:52:33 volumio volumio[881]: SPOTIFY: received: {"type":"volume","data":{"value":64,"max":100}} May 16 07:52:33 volumio volumio[881]: SPOTIFY: RECEIVED SPOTIFY VOLUME 64 May 16 07:52:33 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl May 16 07:52:33 volumio volumio[881]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) May 16 07:52:33 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl May 16 07:52:33 volumio volumio[881]: info: Collecting zipp>=0.5 (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) May 16 07:52:33 volumio volumio[881]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl May 16 07:52:33 volumio volumio[881]: info: Building wheels for collected packages: camilladsp-plot May 16 07:52:33 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp-plot: started May 16 07:52:34 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' May 16 07:52:34 volumio volumio[881]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-fndtuy8p/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 May 16 07:52:34 volumio volumio[881]: info: Successfully built camilladsp-plot May 16 07:52:34 volumio volumio[881]: info: Installing collected packages: typing-extensions, zipp, importlib-metadata, attrs, pyrsistent, importlib-resources, pkgutil-resolve-name, jsonschema, camilladsp-plot May 16 07:52:34 volumio volumio[881]: info: Successfully installed attrs-24.2.0 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 May 16 07:52:34 volumio volumio[881]: info: copying hw detection script May 16 07:52:34 volumio volumio[881]: info: Detected cpu architecture as x86_64 May 16 07:52:35 volumio thd[619]: {"time":1747371150867,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:35 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:35 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:35 volumio volumio[881]: info: May 16 07:52:35 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:35 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:35 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:35 volumio thd[619]: [158B blob data] May 16 07:52:35 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:35 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 May 16 07:52:35 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 64 May 16 07:52:35 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 62 May 16 07:52:35 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:35 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 62 May 16 07:52:35 volumio thd[619]: {"time":1747371155201,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:35 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:35 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:35 volumio volumio[881]: info: May 16 07:52:35 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:35 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:35 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:35 volumio thd[619]: [158B blob data] May 16 07:52:35 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:35 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 May 16 07:52:35 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 62 May 16 07:52:35 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 61 May 16 07:52:35 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:35 volumio thd[619]: {"time":1747371155484,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:35 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:35 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:35 volumio volumio[881]: info: May 16 07:52:35 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:35 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:35 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:35 volumio thd[619]: [158B blob data] May 16 07:52:35 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:35 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 May 16 07:52:35 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 62 May 16 07:52:35 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 60 May 16 07:52:35 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:35 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 60 May 16 07:52:35 volumio thd[619]: {"time":1747371155709,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:35 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:35 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:35 volumio volumio[881]: info: May 16 07:52:35 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:35 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:35 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:35 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:35 volumio thd[619]: [158B blob data] May 16 07:52:35 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:35 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 May 16 07:52:35 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 60 May 16 07:52:35 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 59 May 16 07:52:35 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:36 volumio thd[619]: {"time":1747371155912,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:36 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:36 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:36 volumio volumio[881]: info: May 16 07:52:36 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:36 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:36 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:36 volumio thd[619]: [158B blob data] May 16 07:52:36 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:36 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 May 16 07:52:36 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 60 May 16 07:52:36 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 58 May 16 07:52:36 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:36 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 58 May 16 07:52:36 volumio thd[619]: {"time":1747371156149,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:36 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:36 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:36 volumio volumio[881]: info: May 16 07:52:36 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:36 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:36 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:36 volumio thd[619]: [158B blob data] May 16 07:52:36 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:36 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 May 16 07:52:36 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 58 May 16 07:52:36 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 57 May 16 07:52:36 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:36 volumio thd[619]: {"time":1747371156379,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:36 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:36 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:36 volumio volumio[881]: info: May 16 07:52:36 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:36 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:36 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:36 volumio thd[619]: [158B blob data] May 16 07:52:36 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:36 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 May 16 07:52:36 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 58 May 16 07:52:36 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 56 May 16 07:52:36 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:36 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 56 May 16 07:52:36 volumio thd[619]: {"time":1747371156573,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:36 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:36 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:36 volumio volumio[881]: info: May 16 07:52:36 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:36 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:36 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:36 volumio thd[619]: [158B blob data] May 16 07:52:36 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:36 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 May 16 07:52:36 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 56 May 16 07:52:36 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 55 May 16 07:52:36 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:36 volumio thd[619]: {"time":1747371156753,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:36 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:36 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:36 volumio volumio[881]: info: May 16 07:52:36 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:36 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:36 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:36 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:36 volumio thd[619]: [158B blob data] May 16 07:52:36 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:36 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 16 07:52:36 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 56 May 16 07:52:36 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 54 May 16 07:52:36 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:36 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 54 May 16 07:52:37 volumio thd[619]: {"time":1747371156953,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:37 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:37 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:37 volumio volumio[881]: info: May 16 07:52:37 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:37 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:37 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:37 volumio thd[619]: [158B blob data] May 16 07:52:37 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:37 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 16 07:52:37 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 54 May 16 07:52:37 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 53 May 16 07:52:37 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:37 volumio thd[619]: {"time":1747371157153,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:37 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:37 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:37 volumio volumio[881]: info: May 16 07:52:37 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:37 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:37 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:37 volumio thd[619]: [158B blob data] May 16 07:52:37 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:37 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 May 16 07:52:37 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 54 May 16 07:52:37 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 52 May 16 07:52:37 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:37 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 52 May 16 07:52:37 volumio thd[619]: {"time":1747371157332,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:37 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:37 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:37 volumio volumio[881]: info: May 16 07:52:37 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:37 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:37 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:37 volumio thd[619]: [158B blob data] May 16 07:52:37 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:37 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 16 07:52:37 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 52 May 16 07:52:37 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 51 May 16 07:52:37 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:37 volumio thd[619]: {"time":1747371157528,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:37 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:37 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:37 volumio volumio[881]: info: May 16 07:52:37 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:37 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:37 volumio sudo[2853]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 16 07:52:37 volumio sudo[2853]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:37 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:37 volumio thd[619]: [158B blob data] May 16 07:52:37 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:37 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 May 16 07:52:37 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 52 May 16 07:52:37 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 50 May 16 07:52:37 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:37 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 50 May 16 07:52:37 volumio volumio[881]: info: Reading package lists... May 16 07:52:37 volumio thd[619]: {"time":1747371157735,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:37 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:37 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:37 volumio volumio[881]: info: May 16 07:52:37 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:37 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:37 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:37 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:37 volumio thd[619]: [158B blob data] May 16 07:52:37 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:37 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 May 16 07:52:37 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 50 May 16 07:52:37 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 49 May 16 07:52:37 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:38 volumio volumio[881]: info: Building dependency tree... May 16 07:52:38 volumio volumio[881]: info: Reading state information... May 16 07:52:38 volumio volumio[881]: info: You might want to run 'apt --fix-broken install' to correct these. May 16 07:52:38 volumio volumio[881]: info: The following packages have unmet dependencies: May 16 07:52:38 volumio thd[619]: {"time":1747371157944,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:38 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:38 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:38 volumio volumio[881]: info: May 16 07:52:38 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:38 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:38 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:38 volumio thd[619]: [158B blob data] May 16 07:52:38 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:38 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 May 16 07:52:38 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 50 May 16 07:52:38 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 48 May 16 07:52:38 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:38 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 48 May 16 07:52:38 volumio volumio[881]: info: google-chrome-stable : Depends: fonts-liberation but it is not going to be installed May 16 07:52:38 volumio volumio[881]: info: Depends: libvulkan1 but it is not going to be installed May 16 07:52:38 volumio sudo[2853]: pam_unix(sudo:session): session closed for user root May 16 07:52:38 volumio sudo[2385]: pam_unix(sudo:session): session closed for user root May 16 07:52:38 volumio volumio[881]: info: Install script completed May 16 07:52:38 volumio volumio[881]: info: Adding reference to registry May 16 07:52:38 volumio volumio[881]: info: Done installing plugin. May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 16 07:52:38 volumio volumio[881]: Plugin install end detected on script May 16 07:52:38 volumio volumio[881]: info: Folder /tmp/plugins removed May 16 07:52:38 volumio volumio[881]: info: Folder /tmp/downloaded_plugin.zip removed May 16 07:52:38 volumio volumio[881]: info: Folder /data/temp removed May 16 07:52:38 volumio thd[619]: {"time":1747371158165,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:38 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:38 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:38 volumio volumio[881]: info: May 16 07:52:38 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:38 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:38 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:38 volumio thd[619]: [158B blob data] May 16 07:52:38 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:38 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 May 16 07:52:38 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 48 May 16 07:52:38 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 47 May 16 07:52:38 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:38 volumio thd[619]: {"time":1747371158366,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:38 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:38 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:38 volumio volumio[881]: info: May 16 07:52:38 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:38 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:38 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:38 volumio thd[619]: [158B blob data] May 16 07:52:38 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:38 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 May 16 07:52:38 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 48 May 16 07:52:38 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 46 May 16 07:52:38 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:38 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 46 May 16 07:52:38 volumio thd[619]: {"time":1747371158557,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:38 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:38 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:38 volumio volumio[881]: info: May 16 07:52:38 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:38 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:38 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:38 volumio thd[619]: [158B blob data] May 16 07:52:38 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:38 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 May 16 07:52:38 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 46 May 16 07:52:38 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 45 May 16 07:52:38 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 07:52:38 volumio thd[619]: {"time":1747371158752,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus May 16 07:52:38 volumio thd[619]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 07:52:38 volumio thd[619]: Dload Upload Total Spent Left Speed May 16 07:52:38 volumio volumio[881]: info: May 16 07:52:38 volumio volumio[881]: ---------------------------- Client requests Volume - May 16 07:52:38 volumio volumio[881]: info: VolumeController::SetAlsaVolume- May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioGetState May 16 07:52:38 volumio volumio[881]: info: CoreStateMachine::pushState May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 07:52:38 volumio volumio[881]: info: CoreCommandRouter::volumioPushState May 16 07:52:38 volumio thd[619]: [158B blob data] May 16 07:52:38 volumio volumio[881]: info: PeppySpectrum ---peppyspectrum status May 16 07:52:38 volumio volumio[881]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 May 16 07:52:38 volumio volumio[881]: SPOTIFY: SPOTIFY VOLUME 46 May 16 07:52:38 volumio volumio[881]: SPOTIFY: VOLUMIO VOLUME 44 May 16 07:52:38 volumio volumio[881]: SPOTIFY: DELTA VOLUME ENOUGH: true May 16 07:52:38 volumio volumio[881]: info: Setting Spotify Volume from Volumio: 44 May 16 07:52:40 volumio volumio[881]: info: Enabling plugin fusiondsp May 16 07:52:40 volumio volumio[881]: info: Loading plugin "fusiondsp"... May 16 07:52:40 volumio volumio[881]: info: Preparing to generate the ALSA configuration file May 16 07:52:40 volumio volumio[881]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 16 07:52:40 volumio volumio[881]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 16 07:52:40 volumio volumio[881]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 16 07:52:40 volumio volumio[881]: info: Reading ALSA contributions from plugins. May 16 07:52:40 volumio volumio[881]: info: Asound.conf file written May 16 07:52:40 volumio sudo[2906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 16 07:52:40 volumio sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio sudo[2906]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: info: Output device has changed, restarting MPD May 16 07:52:40 volumio sudo[2911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 07:52:40 volumio sudo[2911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio sudo[2911]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: info: Output device has changed, restarting Shairport Sync May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 07:52:40 volumio sudo[2914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 07:52:40 volumio sudo[2914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio systemd[1]: musicservicesshield.service: Succeeded. May 16 07:52:40 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 16 07:52:40 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 16 07:52:40 volumio systemd[1]: Stopping Music Player Daemon... May 16 07:52:40 volumio systemd[1]: mpd.service: Succeeded. May 16 07:52:40 volumio systemd[1]: Stopped Music Player Daemon. May 16 07:52:40 volumio volumio[881]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 07:52:40 volumio volumio[881]: info: PLUGIN START: fusiondsp May 16 07:52:40 volumio volumio[881]: info: Loading i18n strings for locale en May 16 07:52:40 volumio volumio[881]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. May 16 07:52:40 volumio volumio[881]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. May 16 07:52:40 volumio volumio[881]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 16 07:52:40 volumio volumio[881]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: FusionDsp - mixtype--------------------- Software May 16 07:52:40 volumio volumio[881]: info: Preparing to generate the ALSA configuration file May 16 07:52:40 volumio volumio[881]: info: Done. May 16 07:52:40 volumio volumio[881]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 16 07:52:40 volumio volumio[881]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 16 07:52:40 volumio volumio[881]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 16 07:52:40 volumio volumio[881]: info: Reading ALSA contributions from plugins. May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 07:52:40 volumio volumio[881]: info: MPD Permissions set May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 07:52:40 volumio systemd[1]: Starting Music Player Daemon... May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: FusionDsp - May 16 07:52:40 volumio sudo[2919]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 07:52:40 volumio sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio sudo[2919]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: info: FusionDsp - undefined May 16 07:52:40 volumio volumio[881]: info: Starting Shairport Sync May 16 07:52:40 volumio volumio[881]: SPOTIFY: SETTING SPOTIFY VOLUME 44 May 16 07:52:40 volumio volumio[881]: info: Sending Spotify command with payload to local API: /player/volume May 16 07:52:40 volumio go-librespot[1789]: time="2025-05-16T07:52:40+03:00" level=debug msg="update volume to 28835/65535" May 16 07:52:40 volumio volumio[881]: info: Asound.conf file unchanged, so no further update is needed May 16 07:52:40 volumio volumio[881]: info: Output device has changed, restarting MPD May 16 07:52:40 volumio sudo[2929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 07:52:40 volumio sudo[2929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 07:52:40 volumio systemd[1]: shairport-sync.service: Succeeded. May 16 07:52:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 07:52:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 07:52:40 volumio sudo[2933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 07:52:40 volumio sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio sudo[2929]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio sudo[2933]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: info: Output device has changed, restarting Shairport Sync May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 07:52:40 volumio sudo[2938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 07:52:40 volumio sudo[2938]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 16 07:52:40 volumio systemd[1]: mpd.service: Succeeded. May 16 07:52:40 volumio systemd[1]: Stopped Music Player Daemon. May 16 07:52:40 volumio volumio[881]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 07:52:40 volumio volumio[881]: info: MPD Permissions set May 16 07:52:40 volumio volumio[881]: info: Shairport-Sync Started May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio systemd[1]: Starting Music Player Daemon... May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 07:52:40 volumio sudo[2943]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 07:52:40 volumio sudo[2943]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio sudo[2943]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:40 volumio volumio[881]: info: Starting Shairport Sync May 16 07:52:40 volumio sudo[2953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 07:52:40 volumio sudo[2953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 07:52:40 volumio systemd[1]: shairport-sync.service: Succeeded. May 16 07:52:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 07:52:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 07:52:40 volumio sudo[2953]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: info: Shairport-Sync Started May 16 07:52:40 volumio mpd[2950]: May 16 07:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 16 07:52:40 volumio systemd[1]: Started Music Player Daemon. May 16 07:52:40 volumio sudo[2938]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio sudo[2914]: pam_unix(sudo:session): session closed for user root May 16 07:52:40 volumio volumio[881]: error: MPD error: The expression evaluated to a falsy value: May 16 07:52:40 volumio volumio[881]: assert.ok(self.idling) May 16 07:52:40 volumio volumio[881]: error: The expression evaluated to a falsy value: May 16 07:52:40 volumio volumio[881]: assert.ok(self.idling) May 16 07:52:40 volumio volumio[881]: error: updateQueue error: null May 16 07:52:40 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 16 07:52:41 volumio volumio[2960]: cset: --> shielding system active with May 16 07:52:41 volumio volumio[2960]: cset: "system" cpuset of CPUSPEC(1-3) with 197 tasks running May 16 07:52:41 volumio volumio[2960]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 16 07:52:41 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 16 07:52:41 volumio go-librespot[1789]: time="2025-05-16T07:52:41+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 16 07:52:41 volumio go-librespot[1789]: time="2025-05-16T07:52:41+03:00" level=trace msg="emitting websocket event: volume" May 16 07:52:41 volumio volumio[881]: SPOTIFY: received: {"type":"volume","data":{"value":44,"max":100}} May 16 07:52:41 volumio volumio[881]: SPOTIFY: RECEIVED SPOTIFY VOLUME 44 May 16 07:52:42 volumio volumio[881]: info: camilladsp service started and running in background, instance 1 May 16 07:52:42 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 07:52:42 volumio volumio[881]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 192000 <-AAAAAAAAAAAAA May 16 07:52:42 volumio volumio[881]: info: FusionDsp loaded May 16 07:52:42 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 07:52:42 volumio sudo[2987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 16 07:52:42 volumio sudo[2987]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:42 volumio volumio[881]: info: FusionDsp - Reporting Fusion DSP Enabled May 16 07:52:42 volumio volumio[881]: info: Adding Signal Path Element [object Object] May 16 07:52:42 volumio volumio[881]: info: Adding fusiondspeq DSP Signal Path Element May 16 07:52:42 volumio volumio[881]: info: FusionDsp - ---- installed callbackRead May 16 07:52:42 volumio volumio[881]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 16 07:52:42 volumio volumio[881]: error: FusionDsp - WebSocket error: [object Object] May 16 07:52:42 volumio volumio[881]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 16 07:52:42 volumio systemd[1]: Started FusionDsp Daemon. May 16 07:52:42 volumio sudo[2987]: pam_unix(sudo:session): session closed for user root May 16 07:52:42 volumio volumio[2989]: Traceback (most recent call last): May 16 07:52:42 volumio volumio[2989]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in May 16 07:52:42 volumio volumio[2989]: from aiohttp import web May 16 07:52:42 volumio volumio[2989]: ModuleNotFoundError: No module named 'aiohttp' May 16 07:52:42 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 16 07:52:42 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 16 07:52:43 volumio volumio[881]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/amd64 May 16 07:52:43 volumio volumio[881]: 0+0 records in May 16 07:52:43 volumio volumio[881]: 0+0 records out May 16 07:52:43 volumio volumio[881]: 0 bytes copied, 3.5365e-05 s, 0.0 kB/s May 16 07:52:44 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 16 07:52:44 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. May 16 07:52:44 volumio systemd[1]: Stopped FusionDsp Daemon. May 16 07:52:44 volumio systemd[1]: Started FusionDsp Daemon. May 16 07:52:44 volumio volumio[3002]: Traceback (most recent call last): May 16 07:52:44 volumio volumio[3002]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in May 16 07:52:44 volumio volumio[3002]: from aiohttp import web May 16 07:52:44 volumio volumio[3002]: ModuleNotFoundError: No module named 'aiohttp' May 16 07:52:44 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 16 07:52:44 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 16 07:52:44 volumio volumio[881]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/amd64 May 16 07:52:44 volumio volumio[881]: info: Folder /tmp/plugins removed May 16 07:52:44 volumio volumio[881]: info: Check plugin dependencies May 16 07:52:44 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 07:52:44 volumio volumio[881]: info: Checking if plugin already exists May 16 07:52:44 volumio volumio[881]: info: Rename folder May 16 07:52:44 volumio volumio[881]: info: Folder /tmp/downloaded_plugin.zip removed May 16 07:52:44 volumio volumio[881]: info: Move to category May 16 07:52:45 volumio volumio[881]: info: Checking if install.sh is present May 16 07:52:45 volumio volumio[881]: info: Executing install.sh May 16 07:52:45 volumio sudo[3015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/install.sh May 16 07:52:45 volumio sudo[3015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:45 volumio sudo[3015]: pam_unix(sudo:session): session closed for user root May 16 07:52:45 volumio volumio[881]: info: Installing mpd http output May 16 07:52:45 volumio volumio[881]: Plugin install end detected on script May 16 07:52:45 volumio volumio[881]: info: Install script completed May 16 07:52:45 volumio volumio[881]: info: Adding reference to registry May 16 07:52:45 volumio volumio[881]: info: Done installing plugin. May 16 07:52:45 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 16 07:52:45 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 16 07:52:45 volumio volumio[881]: info: Folder /tmp/plugins removed May 16 07:52:45 volumio volumio[881]: info: Folder /tmp/downloaded_plugin.zip removed May 16 07:52:45 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 07:52:45 volumio volumio[881]: info: Folder /data/temp removed May 16 07:52:45 volumio volumio[881]: info: FusionDsp - May 16 07:52:45 volumio volumio[881]: info: FusionDsp - undefined May 16 07:52:46 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 16 07:52:46 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. May 16 07:52:46 volumio systemd[1]: Stopped FusionDsp Daemon. May 16 07:52:46 volumio systemd[1]: Started FusionDsp Daemon. May 16 07:52:46 volumio volumio[3017]: Traceback (most recent call last): May 16 07:52:46 volumio volumio[3017]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in May 16 07:52:46 volumio volumio[3017]: from aiohttp import web May 16 07:52:46 volumio volumio[3017]: ModuleNotFoundError: No module named 'aiohttp' May 16 07:52:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 16 07:52:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 16 07:52:47 volumio volumio[881]: info: Enabling plugin mpdoutput May 16 07:52:47 volumio volumio[881]: info: Loading plugin "mpdoutput"... May 16 07:52:48 volumio volumio[881]: info: PLUGIN START: mpdoutput May 16 07:52:48 volumio volumio[881]: info: Done. May 16 07:52:48 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 07:52:48 volumio volumio[881]: info: FusionDsp - May 16 07:52:48 volumio volumio[881]: info: FusionDsp - undefined May 16 07:52:49 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 16 07:52:49 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. May 16 07:52:49 volumio systemd[1]: Stopped FusionDsp Daemon. May 16 07:52:49 volumio systemd[1]: Started FusionDsp Daemon. May 16 07:52:49 volumio volumio[3018]: Traceback (most recent call last): May 16 07:52:49 volumio volumio[3018]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in May 16 07:52:49 volumio volumio[3018]: from aiohttp import web May 16 07:52:49 volumio volumio[3018]: ModuleNotFoundError: No module named 'aiohttp' May 16 07:52:49 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 16 07:52:49 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 16 07:52:49 volumio volumio[881]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 16 07:52:51 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 16 07:52:51 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. May 16 07:52:51 volumio systemd[1]: Stopped FusionDsp Daemon. May 16 07:52:51 volumio systemd[1]: Started FusionDsp Daemon. May 16 07:52:51 volumio volumio[3036]: Traceback (most recent call last): May 16 07:52:51 volumio volumio[3036]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in May 16 07:52:51 volumio volumio[3036]: from aiohttp import web May 16 07:52:51 volumio volumio[3036]: ModuleNotFoundError: No module named 'aiohttp' May 16 07:52:51 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 16 07:52:51 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 16 07:52:52 volumio go-librespot[1789]: time="2025-05-16T07:52:52+03:00" level=trace msg="sent dealer ping" May 16 07:52:52 volumio volumio[881]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 16 07:52:52 volumio volumio[881]: info: FusionDsp - Stopping FusionDsp service May 16 07:52:52 volumio volumio[881]: info: camilladsp stopping service pid 2994... May 16 07:52:52 volumio volumio[881]: info: camilladsp service terminated, instance 1 May 16 07:52:52 volumio go-librespot[1789]: time="2025-05-16T07:52:52+03:00" level=trace msg="received dealer pong" May 16 07:52:52 volumio sudo[3040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 16 07:52:52 volumio sudo[3040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:52 volumio systemd[1]: Stopped FusionDsp Daemon. May 16 07:52:52 volumio sudo[3040]: pam_unix(sudo:session): session closed for user root May 16 07:52:52 volumio volumio[881]: info: FusionDsp - Reporting Fusion DSP Disabled May 16 07:52:52 volumio volumio[881]: info: Removing fusiondspeq DSP Signal Path Element May 16 07:52:52 volumio volumio[881]: info: Check plugin dependencies May 16 07:52:52 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 07:52:52 volumio volumio[881]: info: Rename folder May 16 07:52:52 volumio volumio[881]: info: Folder /tmp/downloaded_plugin.zip removed May 16 07:52:52 volumio volumio[881]: info: Move to category May 16 07:52:53 volumio volumio[881]: info: Checking if install.sh is present May 16 07:52:53 volumio volumio[881]: info: Executing install.sh May 16 07:52:53 volumio sudo[3054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 16 07:52:53 volumio sudo[3054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:53 volumio volumio[881]: info: creating filters folder and copying demo filters May 16 07:52:53 volumio volumio[881]: info: copying demo flters May 16 07:52:53 volumio volumio[881]: info: ./presets/ May 16 07:52:53 volumio volumio[881]: info: ./presets/2XEQ15/ May 16 07:52:53 volumio volumio[881]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 16 07:52:53 volumio sudo[3079]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 16 07:52:53 volumio sudo[3079]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:53 volumio volumio[881]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/PEQ/ May 16 07:52:53 volumio volumio[881]: info: ./presets/convfir/ May 16 07:52:53 volumio volumio[881]: info: ./presets/convfir/kef.json May 16 07:52:53 volumio volumio[881]: info: ./presets/convfir/test.json May 16 07:52:53 volumio volumio[881]: info: ./presets/EQ15/ May 16 07:52:53 volumio volumio[881]: info: ./presets/EQ15/.Bass-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/EQ15/.Classic-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/EQ15/.Voice-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: ./presets/EQ15/.Rock-FusionDsp.json May 16 07:52:53 volumio volumio[881]: info: Installing/fusiondsp dependencies May 16 07:52:53 volumio volumio[881]: info: Hit:1 http://deb.debian.org/debian buster InRelease May 16 07:52:53 volumio volumio[881]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease May 16 07:52:54 volumio volumio[881]: info: Reading package lists... May 16 07:52:55 volumio volumio[881]: info: Building dependency tree... May 16 07:52:55 volumio volumio[881]: info: Reading state information... May 16 07:52:55 volumio volumio[881]: info: All packages are up to date. May 16 07:52:55 volumio sudo[3079]: pam_unix(sudo:session): session closed for user root May 16 07:52:55 volumio sudo[3288]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 16 07:52:55 volumio sudo[3288]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:55 volumio volumio[881]: info: Reading package lists... May 16 07:52:55 volumio volumio[881]: info: Building dependency tree... May 16 07:52:55 volumio volumio[881]: info: Reading state information... May 16 07:52:55 volumio volumio[881]: info: python3-pip is already the newest version (18.1-5). May 16 07:52:55 volumio volumio[881]: info: You might want to run 'apt --fix-broken install' to correct these. May 16 07:52:55 volumio volumio[881]: info: The following packages have unmet dependencies: May 16 07:52:55 volumio volumio[881]: info: google-chrome-stable : Depends: fonts-liberation but it is not going to be installed May 16 07:52:55 volumio volumio[881]: info: Depends: libvulkan1 but it is not going to be installed May 16 07:52:55 volumio volumio[881]: info: python3-aiohttp : Depends: python3-yarl (>= 1.0) but it is not going to be installed May 16 07:52:55 volumio volumio[881]: info: Depends: python3-async-timeout but it is not going to be installed May 16 07:52:55 volumio volumio[881]: info: Depends: python3-attr but it is not going to be installed May 16 07:52:55 volumio volumio[881]: info: Depends: python3-multidict but it is not going to be installed May 16 07:52:55 volumio sudo[3288]: pam_unix(sudo:session): session closed for user root May 16 07:52:55 volumio sudo[3292]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 16 07:52:55 volumio sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:55 volumio sudo[3292]: pam_unix(sudo:session): session closed for user root May 16 07:52:55 volumio volumio[881]: info: lib/systemd/system/fusiondsp.service May 16 07:52:58 volumio volumio[881]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 16 07:52:58 volumio volumio[881]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 16 07:52:58 volumio volumio[881]: info: May 16 07:52:58 volumio volumio[881]: info: cgui-1.0.0.zip opened May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/LICENSE.txt May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/README.md May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/build/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/robots.txt May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/logo512.png May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/asset-manifest.json May 16 07:52:58 volumio sudo[3340]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/index.html May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/.put_statics_here May 16 07:52:58 volumio sudo[3340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/favicon.ico May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/logo192.png May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/manifest.json May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/css-variables.css May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/service-worker.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/build/static/ May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/build/static/css/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 16 07:52:58 volumio sudo[3340]: pam_unix(sudo:session): session closed for user root May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/build/static/media/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/build/static/js/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.71e11955.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/config/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/config/camillagui.yml May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 16 07:52:58 volumio sudo[3342]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 16 07:52:58 volumio sudo[3342]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/config/gui-config.yml May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/backend/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/filters_test.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/version.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/filters.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/settings.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/views.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/filterdefaults.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__init__.py May 16 07:52:58 volumio volumio[881]: info: creating directory: cgui/backend/__pycache__/ May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 16 07:52:58 volumio sudo[3342]: pam_unix(sudo:session): session closed for user root May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/routes.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/backend/filemanagement.py May 16 07:52:58 volumio volumio[881]: info: extracting: cgui/main.py May 16 07:52:58 volumio sudo[3344]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 16 07:52:58 volumio sudo[3344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:52:58 volumio sudo[3344]: pam_unix(sudo:session): session closed for user root May 16 07:52:59 volumio volumio[881]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 May 16 07:52:59 volumio volumio[881]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-frqg2vex May 16 07:53:01 volumio volumio[881]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages May 16 07:53:01 volumio volumio[881]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) May 16 07:53:01 volumio volumio[881]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) May 16 07:53:01 volumio volumio[881]: info: Building wheels for collected packages: camilladsp May 16 07:53:01 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp: started May 16 07:53:01 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' May 16 07:53:01 volumio volumio[881]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-ue4hzavg/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 May 16 07:53:02 volumio volumio[881]: info: Successfully built camilladsp May 16 07:53:03 volumio volumio[881]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 May 16 07:53:03 volumio volumio[881]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-z84eux58 May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (24.2.0) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) May 16 07:53:04 volumio volumio[881]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) May 16 07:53:04 volumio volumio[881]: info: Building wheels for collected packages: camilladsp-plot May 16 07:53:04 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp-plot: started May 16 07:53:05 volumio volumio[881]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' May 16 07:53:05 volumio volumio[881]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-14kr2nxh/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 May 16 07:53:05 volumio volumio[881]: info: Successfully built camilladsp-plot May 16 07:53:05 volumio volumio[881]: info: copying hw detection script May 16 07:53:05 volumio volumio[881]: info: Detected cpu architecture as x86_64 May 16 07:53:06 volumio sudo[3424]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 16 07:53:06 volumio sudo[3424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 07:53:06 volumio volumio[881]: info: Reading package lists... May 16 07:53:06 volumio volumio[881]: info: Building dependency tree... May 16 07:53:06 volumio volumio[881]: info: Reading state information... May 16 07:53:06 volumio volumio[881]: info: You might want to run 'apt --fix-broken install' to correct these. May 16 07:53:06 volumio volumio[881]: info: The following packages have unmet dependencies: May 16 07:53:07 volumio volumio[881]: info: google-chrome-stable : Depends: fonts-liberation but it is not going to be installed May 16 07:53:07 volumio volumio[881]: info: Depends: libvulkan1 but it is not going to be installed May 16 07:53:07 volumio sudo[3424]: pam_unix(sudo:session): session closed for user root May 16 07:53:07 volumio sudo[3054]: pam_unix(sudo:session): session closed for user root May 16 07:53:07 volumio volumio[881]: Plugin install end detected on script May 16 07:53:07 volumio volumio[881]: info: Install script completed May 16 07:53:07 volumio volumio[881]: info: Done installing plugin. May 16 07:53:07 volumio volumio[881]: info: Enabling plugin fusiondsp May 16 07:53:07 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 16 07:53:07 volumio volumio[881]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Logitech Media Server","name":"lms","category":"music_service","version":"1.2.6","icon":"fa fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Volumio Onkyo Control","name":"onkyo_control","category":"system_hardware","version":"1.1.2","icon":"fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true}] May 16 07:53:07 volumio volumio[881]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 16 07:53:07 volumio volumio[881]: info: Folder /tmp/plugins removed May 16 07:53:07 volumio volumio[881]: info: Folder /tmp/downloaded_plugin.zip removed May 16 07:53:07 volumio volumio[881]: info: Folder /data/temp removed May 16 07:53:07 volumio volumio[881]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 07:53:07 volumio volumio[881]: info: FusionDsp - May 16 07:53:07 volumio volumio[881]: info: FusionDsp - undefined May 16 07:53:15 volumio volumio[881]: info: Starting Uninstall of plugin audio_interface - fusiondsp May 16 07:53:15 volumio volumio[881]: info: Uninstalling plugin fusiondsp May 16 07:53:15 volumio volumio[881]: info: FusionDsp - Stopping FusionDsp service May 16 07:53:15 volumio volumio[881]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 07:53:15 volumio volumio[881]: TypeError: Cannot read property 'stop' of null May 16 07:53:15 volumio volumio[881]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 16 07:53:15 volumio volumio[881]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 16 07:53:15 volumio volumio[881]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) May 16 07:53:15 volumio volumio[881]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) May 16 07:53:15 volumio volumio[881]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) May 16 07:53:15 volumio volumio[881]: at Socket.emit (events.js:400:28) May 16 07:53:15 volumio volumio[881]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 16 07:53:15 volumio volumio[881]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 16 07:53:15 volumio volumio[881]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 07:53:15 volumio sudo[3451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 07:52 May 16 07:53:15 volumio sudo[3451]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"