-- Logs begin at Sat 2025-06-28 16:34:46 +08, end at Sat 2025-06-28 16:38:48 +08. -- Jun 28 16:37:11 volumio ntpd[1471]: Soliciting pool server 47.250.183.141 Jun 28 16:37:11 volumio ntpd[1471]: Soliciting pool server 111.90.158.16 Jun 28 16:37:14 volumio ntpd[1471]: Soliciting pool server 2001:ac8:a:23:0:1:0:26 Jun 28 16:37:16 volumio ntpd[1471]: Soliciting pool server 111.90.158.16 Jun 28 16:37:26 volumio volumio[1076]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/amd64 Jun 28 16:37:28 volumio volumio[1076]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/amd64 Jun 28 16:37:28 volumio volumio[1076]: info: minidlna: Stopping minidlna.service Jun 28 16:37:28 volumio sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop minidlna.service Jun 28 16:37:28 volumio sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:37:28 volumio systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... Jun 28 16:37:28 volumio systemd[1]: minidlna.service: Succeeded. Jun 28 16:37:28 volumio systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. Jun 28 16:37:28 volumio sudo[2676]: pam_unix(sudo:session): session closed for user root Jun 28 16:37:28 volumio volumio[1076]: info: minidlna: systemctl stop minidlna.service succeeded. Jun 28 16:37:28 volumio volumio[1076]: info: Check plugin dependencies Jun 28 16:37:28 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 16:37:28 volumio volumio[1076]: info: Rename folder Jun 28 16:37:28 volumio volumio[1076]: info: Folder /tmp/downloaded_plugin.zip removed Jun 28 16:37:28 volumio volumio[1076]: info: Move to category Jun 28 16:37:32 volumio volumio[1076]: info: Checking if install.sh is present Jun 28 16:37:32 volumio volumio[1076]: info: Executing install.sh Jun 28 16:37:32 volumio sudo[2690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/minidlna/install.sh Jun 28 16:37:32 volumio sudo[2690]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:37:32 volumio volumio[1076]: info: Completing "UIConfig.json" Jun 28 16:37:32 volumio volumio[1076]: info: Installing MiniDLNA Jun 28 16:37:32 volumio volumio[1076]: info: Hit:1 http://deb.debian.org/debian buster InRelease Jun 28 16:37:32 volumio volumio[1076]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Jun 28 16:37:38 volumio volumio[1076]: info: Reading package lists... Jun 28 16:37:38 volumio volumio[1076]: info: Reading package lists... Jun 28 16:37:38 volumio volumio[1076]: info: Building dependency tree... Jun 28 16:37:39 volumio volumio[1076]: info: Reading state information... Jun 28 16:37:40 volumio volumio[1076]: info: minidlna is already the newest version (1.2.1+dfsg-2+deb10u3). Jun 28 16:37:40 volumio volumio[1076]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jun 28 16:37:41 volumio systemd[1]: Reloading. Jun 28 16:37:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 28 16:37:41 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Jun 28 16:37:41 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 28 16:37:41 volumio systemd[1]: Reloading. Jun 28 16:37:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 28 16:37:41 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Jun 28 16:37:41 volumio systemd[1]: Reloading. Jun 28 16:37:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 28 16:37:42 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Jun 28 16:37:42 volumio volumio[1076]: info: Creating systemd unit /etc/systemd/system/minidlna.service Jun 28 16:37:42 volumio systemd[1]: Reloading. Jun 28 16:37:42 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 28 16:37:42 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Jun 28 16:37:42 volumio volumio[1076]: info: Setting values for "network_interface" and "model_number" in /data/plugins/music_service/minidlna/config.json Jun 28 16:37:42 volumio volumio[1076]: info: Setting permissions to MiniDLNA folders Jun 28 16:37:42 volumio sudo[2690]: pam_unix(sudo:session): session closed for user root Jun 28 16:37:42 volumio volumio[1076]: Plugin install end detected on script Jun 28 16:37:42 volumio volumio[1076]: info: Install script completed Jun 28 16:37:42 volumio volumio[1076]: info: Done installing plugin. Jun 28 16:37:42 volumio volumio[1076]: info: Enabling plugin minidlna Jun 28 16:37:42 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 16:37:42 volumio volumio[1076]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":true},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Logitech Media Server","name":"lms","category":"music_service","version":"1.2.6","icon":"fa fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":false},{"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":false,"active":false},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":false,"active":false}] Jun 28 16:37:42 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 28 16:37:42 volumio volumio[1076]: info: Folder /tmp/plugins removed Jun 28 16:37:42 volumio volumio[1076]: info: Folder /tmp/downloaded_plugin.zip removed Jun 28 16:37:42 volumio volumio[1076]: info: Folder /data/temp removed Jun 28 16:37:42 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 16:37:42 volumio volumio[1076]: info: FusionDsp - Jun 28 16:37:42 volumio volumio[1076]: info: FusionDsp - undefined Jun 28 16:37:54 volumio volumio[1076]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 28 16:37:57 volumio volumio[1076]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 28 16:37:57 volumio volumio[1076]: info: FusionDsp - Stopping FusionDsp service Jun 28 16:37:57 volumio volumio[1076]: info: camilladsp stopping service pid 2282... Jun 28 16:37:57 volumio volumio[1076]: info: camilladsp service terminated, instance 1 Jun 28 16:37:57 volumio sudo[3093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jun 28 16:37:57 volumio sudo[3093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:37:57 volumio systemd[1]: Stopping FusionDsp Daemon... Jun 28 16:37:57 volumio volumio[1867]: Backend configuration: Jun 28 16:37:57 volumio volumio[1867]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 28 16:37:57 volumio volumio[1867]: active_config_txt: null Jun 28 16:37:57 volumio volumio[1867]: camilla_host: 127.0.0.1 Jun 28 16:37:57 volumio volumio[1867]: camilla_port: 9876 Jun 28 16:37:57 volumio volumio[1867]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jun 28 16:37:57 volumio volumio[1867]: config_dir: /data/configuration/audio_interface/fusiondsp Jun 28 16:37:57 volumio volumio[1867]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 28 16:37:57 volumio volumio[1867]: log_file: /tmp/camilladsp.log Jun 28 16:37:57 volumio volumio[1867]: on_get_active_config: null Jun 28 16:37:57 volumio volumio[1867]: on_set_active_config: null Jun 28 16:37:57 volumio volumio[1867]: port: 5011 Jun 28 16:37:57 volumio volumio[1867]: supported_capture_types: null Jun 28 16:37:57 volumio volumio[1867]: supported_playback_types: null Jun 28 16:37:57 volumio volumio[1867]: update_config_symlink: false Jun 28 16:37:57 volumio volumio[1867]: update_config_txt: false Jun 28 16:37:57 volumio volumio[1867]: ======== Running on http://0.0.0.0:5011 ======== Jun 28 16:37:57 volumio volumio[1867]: (Press CTRL+C to quit) Jun 28 16:37:57 volumio systemd[1]: fusiondsp.service: Succeeded. Jun 28 16:37:57 volumio systemd[1]: Stopped FusionDsp Daemon. Jun 28 16:37:57 volumio sudo[3093]: pam_unix(sudo:session): session closed for user root Jun 28 16:37:57 volumio volumio[1076]: info: FusionDsp - Reporting Fusion DSP Disabled Jun 28 16:37:57 volumio volumio[1076]: info: Removing fusiondspeq DSP Signal Path Element Jun 28 16:37:57 volumio volumio[1076]: info: Check plugin dependencies Jun 28 16:37:57 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 16:37:57 volumio volumio[1076]: info: Rename folder Jun 28 16:37:57 volumio volumio[1076]: info: Folder /tmp/downloaded_plugin.zip removed Jun 28 16:37:57 volumio volumio[1076]: info: Move to category Jun 28 16:38:16 volumio volumio-remote-updater[874]: [2025-06-28 16:38:16] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jun 28 16:38:19 volumio ntpd[1471]: Soliciting pool server 2402:1f00:8000:800::2812 Jun 28 16:38:21 volumio volumio-remote-updater[874]: [2025-06-28 16:38:21] [connect] Successful connection Jun 28 16:38:22 volumio ntpd[1471]: Soliciting pool server 47.254.196.78 Jun 28 16:38:31 volumio volumio[1076]: info: Checking if install.sh is present Jun 28 16:38:31 volumio volumio[1076]: info: Executing install.sh Jun 28 16:38:31 volumio volumio[1076]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 28 16:38:31 volumio sudo[3155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jun 28 16:38:31 volumio sudo[3155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:31 volumio volumio[1076]: info: creating filters folder and copying demo filters Jun 28 16:38:31 volumio volumio[1076]: info: copying demo flters Jun 28 16:38:32 volumio volumio[1076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 28 16:38:32 volumio volumio[1076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:32 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:32 volumio volumio[1076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 28 16:38:32 volumio volumio[1076]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:32 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:32 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 28 16:38:32 volumio volumio[1076]: info: Received Get System Info Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 16:38:32 volumio volumio[1076]: info: Discovery: Getting this device information Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:32 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 16:38:32 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:32 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:32 volumio volumio[1076]: info: Listing playlists Jun 28 16:38:33 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 16:38:33 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 16:38:33 volumio volumio[1076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 28 16:38:33 volumio volumio[1076]: info: FusionDsp - Jun 28 16:38:33 volumio volumio[1076]: info: FusionDsp - Jun 28 16:38:33 volumio volumio[1076]: info: FusionDsp - undefined Jun 28 16:38:33 volumio volumio[1076]: info: FusionDsp - undefined Jun 28 16:38:33 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:33 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 28 16:38:34 volumio volumio-remote-updater[874]: [2025-06-28 16:38:34] [connect] Successful connection Jun 28 16:38:34 volumio volumio-remote-updater[874]: [2025-06-28 16:38:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751099914 101 Jun 28 16:38:34 volumio volumio[1076]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/ Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/2XEQ15/ Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Jun 28 16:38:35 volumio sudo[3197]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Jun 28 16:38:35 volumio sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/PEQ/ Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/convfir/ Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/convfir/kef.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/convfir/test.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/EQ15/ Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/EQ15/.Bass-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/EQ15/.Classic-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/EQ15/.Voice-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: ./presets/EQ15/.Rock-FusionDsp.json Jun 28 16:38:35 volumio volumio[1076]: info: Installing/fusiondsp dependencies Jun 28 16:38:35 volumio volumio[1076]: info: Hit:1 http://deb.debian.org/debian buster InRelease Jun 28 16:38:35 volumio volumio[1076]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Jun 28 16:38:36 volumio volumio[1076]: info: Reading package lists... Jun 28 16:38:36 volumio volumio[1076]: info: Building dependency tree... Jun 28 16:38:36 volumio volumio[1076]: info: Reading state information... Jun 28 16:38:36 volumio volumio[1076]: info: All packages are up to date. Jun 28 16:38:36 volumio sudo[3197]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:36 volumio sudo[3406]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jun 28 16:38:36 volumio sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:36 volumio volumio[1076]: info: Reading package lists... Jun 28 16:38:37 volumio volumio[1076]: info: Building dependency tree... Jun 28 16:38:37 volumio volumio[1076]: info: Reading state information... Jun 28 16:38:37 volumio volumio[1076]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jun 28 16:38:37 volumio sudo[3406]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:37 volumio volumio[1076]: info: python3-pip is already the newest version (18.1-5). Jun 28 16:38:37 volumio volumio[1076]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jun 28 16:38:37 volumio sudo[3410]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jun 28 16:38:37 volumio sudo[3410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:37 volumio volumio[1076]: info: lib/systemd/system/fusiondsp.service Jun 28 16:38:37 volumio sudo[3410]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:38 volumio volumio[1076]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jun 28 16:38:38 volumio volumio[1076]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jun 28 16:38:38 volumio volumio[1076]: info: Jun 28 16:38:38 volumio volumio[1076]: info: cgui-1.0.0.zip opened Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/LICENSE.txt Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/README.md Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/build/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/robots.txt Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/logo512.png Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/asset-manifest.json Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/index.html Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/.put_statics_here Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/favicon.ico Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/logo192.png Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jun 28 16:38:38 volumio sudo[3414]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/manifest.json Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/css-variables.css Jun 28 16:38:38 volumio sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/service-worker.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/build/static/ Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/build/static/css/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/build/static/media/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jun 28 16:38:38 volumio sudo[3414]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/build/static/js/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.71e11955.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/config/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/config/camillagui.yml Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/config/gui-config.yml Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/backend/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/filters_test.py Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/version.py Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/filters.py Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/settings.py Jun 28 16:38:38 volumio sudo[3416]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jun 28 16:38:38 volumio sudo[3416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/views.py Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/filterdefaults.py Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__init__.py Jun 28 16:38:38 volumio volumio[1076]: info: creating directory: cgui/backend/__pycache__/ Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/routes.py Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/backend/filemanagement.py Jun 28 16:38:38 volumio sudo[3416]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:38 volumio volumio[1076]: info: extracting: cgui/main.py Jun 28 16:38:38 volumio sudo[3418]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jun 28 16:38:38 volumio sudo[3418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:38 volumio sudo[3418]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:40 volumio volumio[1076]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jun 28 16:38:40 volumio volumio[1076]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-91_y40r6 Jun 28 16:38:40 volumio volumio[1076]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 28 16:38:41 volumio volumio[1076]: 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 Jun 28 16:38:41 volumio volumio[1076]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Jun 28 16:38:41 volumio volumio[1076]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Jun 28 16:38:41 volumio volumio[1076]: info: Building wheels for collected packages: camilladsp Jun 28 16:38:41 volumio volumio[1076]: info: Running setup.py bdist_wheel for camilladsp: started Jun 28 16:38:41 volumio volumio[1076]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jun 28 16:38:41 volumio volumio[1076]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-0e780n5r/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jun 28 16:38:41 volumio volumio[1076]: info: Successfully built camilladsp Jun 28 16:38:42 volumio volumio[1076]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jun 28 16:38:42 volumio volumio[1076]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-omheerr6 Jun 28 16:38:43 volumio volumio[1076]: 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 Jun 28 16:38:43 volumio volumio[1076]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jun 28 16:38:43 volumio volumio[1076]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jun 28 16:38:43 volumio volumio[1076]: 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) Jun 28 16:38:43 volumio volumio[1076]: 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) Jun 28 16:38:43 volumio volumio[1076]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jun 28 16:38:43 volumio volumio[1076]: 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) Jun 28 16:38:43 volumio volumio[1076]: 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) Jun 28 16:38:43 volumio volumio[1076]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 28 16:38:43 volumio volumio[1076]: info: FusionDsp - Stopping FusionDsp service Jun 28 16:38:43 volumio volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 16:38:43 volumio volumio[1076]: TypeError: Cannot read property 'stop' of null Jun 28 16:38:43 volumio volumio[1076]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jun 28 16:38:43 volumio volumio[1076]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 28 16:38:43 volumio volumio[1076]: at /volumio/app/pluginmanager.js:956:12 Jun 28 16:38:43 volumio volumio[1076]: at ChildProcess.exithandler (child_process.js:374:7) Jun 28 16:38:43 volumio volumio[1076]: at ChildProcess.emit (events.js:400:28) Jun 28 16:38:43 volumio volumio[1076]: at maybeClose (internal/child_process.js:1088:16) Jun 28 16:38:43 volumio volumio[1076]: at Socket. (internal/child_process.js:446:11) Jun 28 16:38:43 volumio volumio[1076]: at Socket.emit (events.js:400:28) Jun 28 16:38:43 volumio volumio[1076]: at Pipe. (net.js:686:12) Jun 28 16:38:43 volumio volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 16:38:43 volumio sudo[3477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 16:37 Jun 28 16:38:43 volumio sudo[3477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:43 volumio sudo[3477]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:44 volumio volumio-remote-updater[874]: [2025-06-28 16:38:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 28 16:38:44 volumio volumio-remote-updater[874]: [2025-06-28 16:38:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 28 16:38:44 volumio go-librespot[1769]: time="2025-06-28T16:38:44+08:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 28 16:38:44 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 28 16:38:44 volumio sudo[3155]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:44 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 28 16:38:44 volumio systemd[1]: Started dynamicswap service. Jun 28 16:38:44 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 28 16:38:44 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 28 16:38:44 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 28 16:38:44 volumio systemd[1]: Started dynamicswap service. Jun 28 16:38:44 volumio systemd[1]: Stopped Volumio Backend Module. Jun 28 16:38:44 volumio systemd[1]: Started Volumio Backend Module. Jun 28 16:38:44 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 28 16:38:45 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:45 volumio volumio[3511]: info: ----- Volumio3 ---- Jun 28 16:38:45 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:45 volumio volumio[3511]: info: ----- System startup ---- Jun 28 16:38:45 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:45 volumio volumio[3511]: info: MYVOLUMIO Environment detected Jun 28 16:38:45 volumio volumio[3511]: info: Plugin folders cleanup Jun 28 16:38:45 volumio volumio[3511]: info: Scanning into folder /volumio/app/plugins/ Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category audio_interface Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category miscellanea Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category music_service Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category plugins.json Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category system_controller Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category user_interface Jun 28 16:38:45 volumio volumio[3511]: info: Scanning into folder /data/plugins/ Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category audio_interface Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category music_service Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category system_controller Jun 28 16:38:45 volumio volumio[3511]: info: Scanning category system_hardware Jun 28 16:38:45 volumio volumio[3511]: info: Plugin folders cleanup completed Jun 28 16:38:45 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:45 volumio volumio[3511]: info: ----- Core plugins startup ---- Jun 28 16:38:45 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugins from folder /volumio/app/plugins/ Jun 28 16:38:45 volumio volumio[3511]: info: Adding plugin upnp to MyMusic Plugins Jun 28 16:38:45 volumio volumio[3511]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 28 16:38:45 volumio volumio[3511]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugins from folder /data/plugins/ Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "system"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "appearance"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "network"... Jun 28 16:38:45 volumio volumio[3511]: info: Refreshing Cached IP Addresses Jun 28 16:38:45 volumio sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 28 16:38:45 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:45 volumio sudo[3537]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:45 volumio sudo[3539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 28 16:38:45 volumio sudo[3539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "services"... Jun 28 16:38:45 volumio sudo[3539]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "alsa_controller"... Jun 28 16:38:45 volumio sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 28 16:38:45 volumio sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:45 volumio sudo[3554]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:45 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "wizard"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "networkfs"... Jun 28 16:38:45 volumio volumio[3511]: info: Starting Udev Watcher for removable devices Jun 28 16:38:45 volumio volumio[3511]: info: Ignoring mount for partition: boot Jun 28 16:38:45 volumio volumio[3511]: info: Ignoring mount for partition: volumio Jun 28 16:38:45 volumio volumio[3511]: info: Ignoring mount for partition: volumio_data Jun 28 16:38:45 volumio volumio[3511]: info: Ignoring mount for partition: 8012-E882 Jun 28 16:38:45 volumio volumio[3511]: error: Cannot associate FS Label, not mounting Jun 28 16:38:45 volumio volumio[3511]: info: Ignoring mount for partition: 4EEE15EEEE15CF59 Jun 28 16:38:45 volumio volumio[3511]: info: Ignoring mount for partition: 40605E40605E3D3A Jun 28 16:38:45 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "volumio_command_line_client"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "upnp"... Jun 28 16:38:45 volumio volumio[3511]: info: [1751099925519] Starting Upmpd Daemon Jun 28 16:38:45 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "my_music"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "mpd"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "upnp_browser"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "alarm-clock"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "airplay_emulation"... Jun 28 16:38:45 volumio volumio[3511]: info: Starting Shairport Sync Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "last_100"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "webradio"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "i2s_dacs"... Jun 28 16:38:45 volumio volumio[3511]: info: I2S DAC not set, start Auto-detection Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "volumiodiscovery"... Jun 28 16:38:45 volumio volumio[3511]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 28 16:38:45 volumio volumio[3511]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 16:38:45 volumio volumio[3511]: *** WARNING *** For more information see Jun 28 16:38:45 volumio volumio[3511]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 28 16:38:45 volumio volumio[3511]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 16:38:45 volumio volumio[3511]: *** WARNING *** For more information see Jun 28 16:38:45 volumio node[3511]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 28 16:38:45 volumio node[3511]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 16:38:45 volumio node[3511]: *** WARNING *** For more information see Jun 28 16:38:45 volumio node[3511]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 28 16:38:45 volumio node[3511]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 28 16:38:45 volumio node[3511]: *** WARNING *** For more information see Jun 28 16:38:45 volumio volumio[3511]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 28 16:38:45 volumio volumio[3511]: info: Discovery: Started advertising with name: Volumio Jun 28 16:38:45 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 28 16:38:45 volumio volumio[3511]: info: Plugin calmradio is not enabled Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "spop"... Jun 28 16:38:45 volumio volumio[3511]: info: Plugin youtube2 is not enabled Jun 28 16:38:45 volumio volumio[3511]: info: Plugin ytmusic is not enabled Jun 28 16:38:45 volumio volumio[3511]: info: Plugin autostart is not enabled Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "outputs"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "albumart"... Jun 28 16:38:45 volumio volumio[3511]: info: Plugin example_plugin is not enabled Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "inputs"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "updater_comm"... Jun 28 16:38:45 volumio volumio[3511]: info: Plugin mpdemulation is not enabled Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "rest_api"... Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "websocket"... Jun 28 16:38:45 volumio volumio[3511]: info: Starting Socket.io Server version 2.3.0 Jun 28 16:38:45 volumio volumio[3511]: info: Loading plugin "fusiondsp"... Jun 28 16:38:45 volumio volumio[3511]: Forking 7 albumart workers Jun 28 16:38:46 volumio volumio[3511]: info: Applying required configuration parameters for plugin fusiondsp Jun 28 16:38:46 volumio volumio[3511]: info: Loading plugin "mpdoutput"... Jun 28 16:38:46 volumio volumio[3511]: info: Loading plugin "lms"... Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: info: Loading plugin "minidlna"... Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: Starting albumart workers Jun 28 16:38:46 volumio volumio[3511]: info: Applying required configuration parameters for plugin minidlna Jun 28 16:38:46 volumio volumio[3511]: info: Plugin music_services_shield is not enabled Jun 28 16:38:46 volumio volumio[3511]: info: Loading i18n strings for locale en Jun 28 16:38:46 volumio volumio[3511]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jun 28 16:38:46 volumio volumio[3511]: Updating browse sources language Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::initPlayerControls Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: Express server listening on port 3000 Jun 28 16:38:46 volumio volumio[3511]: [Metrics] WebUI: 1s 227.37ms Jun 28 16:38:46 volumio volumio[3511]: info: Setting Device type: x86 Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::resetVolumioState Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::getcurrentVolume Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 28 16:38:46 volumio volumio[3511]: info: Completed loading Core Plugins Jun 28 16:38:46 volumio volumio[3511]: info: Preparing to generate the ALSA configuration file Jun 28 16:38:46 volumio volumio[3511]: info: Cannot read play queue from file Jun 28 16:38:46 volumio volumio[3511]: info: Volumio Network Manager: Network status updated: 1 Jun 28 16:38:46 volumio volumio[3511]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 28 16:38:46 volumio volumio[3511]: wlan0 Interface doesn't support scanning. Jun 28 16:38:46 volumio volumio[3511]: info: Cannot use regular scanning, forcing with ap-force Jun 28 16:38:46 volumio sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 28 16:38:46 volumio sudo[3693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio sudo[3693]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:46 volumio volumio[3511]: command failed: No such device (-19) Jun 28 16:38:46 volumio volumio[3511]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 28 16:38:46 volumio volumio[3511]: command failed: No such device (-19) Jun 28 16:38:46 volumio volumio[3511]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 28 16:38:46 volumio volumio[3511]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 28 16:38:46 volumio volumio[3511]: info: Reading ALSA contributions from plugins. Jun 28 16:38:46 volumio volumio[3511]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 28 16:38:46 volumio volumio[3511]: Unable to parse: Jun 28 16:38:46 volumio volumio[3511]: Simple mixer control 'IEC958',0 Jun 28 16:38:46 volumio volumio[3511]: Capabilities: pswitch pswitch-joined Jun 28 16:38:46 volumio volumio[3511]: Playback channels: Mono Jun 28 16:38:46 volumio volumio[3511]: Mono: Playback [on] Jun 28 16:38:46 volumio volumio[3511]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::setRepeat null single undefined Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::pushState Jun 28 16:38:46 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioPushState Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::setRandom null Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::pushState Jun 28 16:38:46 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioPushState Jun 28 16:38:46 volumio volumio[3511]: Unable to parse: Jun 28 16:38:46 volumio volumio[3511]: Simple mixer control 'IEC958',0 Jun 28 16:38:46 volumio volumio[3511]: Capabilities: pswitch pswitch-joined Jun 28 16:38:46 volumio volumio[3511]: Playback channels: Mono Jun 28 16:38:46 volumio volumio[3511]: Mono: Playback [on] Jun 28 16:38:46 volumio volumio[3511]: info: VolumeController:: Volume=undefined Mute =false Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::pushState Jun 28 16:38:46 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioPushState Jun 28 16:38:46 volumio volumio[3511]: info: CoreStateMachine::updateTrackBlock Jun 28 16:38:46 volumio volumio[3511]: info: CorePlayQueue::getTrackBlock Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 16:38:46 volumio volumio[3511]: info: Asound.conf file unchanged, so no further update is needed Jun 28 16:38:46 volumio volumio[3511]: info: Output device has changed, restarting MPD Jun 28 16:38:46 volumio sudo[3704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 28 16:38:46 volumio sudo[3704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio sudo[3704]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:46 volumio volumio[3511]: info: Output device has changed, restarting Shairport Sync Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:46 volumio sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 28 16:38:46 volumio sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio systemd[1]: Stopping Music Player Daemon... Jun 28 16:38:46 volumio volumio[3511]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 16:38:46 volumio systemd[1]: mpd.service: Succeeded. Jun 28 16:38:46 volumio systemd[1]: Stopped Music Player Daemon. Jun 28 16:38:46 volumio volumio[3511]: info: ___________ START PLUGINS ___________ Jun 28 16:38:46 volumio volumio[3511]: info: ControllerMpd::onStart: Initializing MPD Jun 28 16:38:46 volumio volumio[3511]: info: Creating MPD Configuration file Jun 28 16:38:46 volumio sudo[3715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 28 16:38:46 volumio sudo[3715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 16:38:46 volumio volumio[3511]: info: [1751099926401] CoreMusicLibrary::Adding element Media Servers Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:46 volumio sudo[3715]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:46 volumio systemd[1]: Starting Music Player Daemon... Jun 28 16:38:46 volumio sudo[3718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 28 16:38:46 volumio sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 28 16:38:46 volumio systemd[1]: mpd.service: Succeeded. Jun 28 16:38:46 volumio systemd[1]: Stopped Music Player Daemon. Jun 28 16:38:46 volumio systemd[1]: Starting Music Player Daemon... Jun 28 16:38:46 volumio volumio[3511]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 16:38:46 volumio volumio[3511]: info: [1751099926431] CoreMusicLibrary::Adding element Last_100 Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 28 16:38:46 volumio volumio[3511]: info: [1751099926432] CoreMusicLibrary::Adding element Webradio Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 16:38:46 volumio volumio[3511]: info: Initializing BBC Radios Jun 28 16:38:46 volumio sudo[3726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 28 16:38:46 volumio sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio sudo[3726]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: Creating Spotify config file Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: Loading i18n strings for locale en Jun 28 16:38:46 volumio volumio[3511]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 28 16:38:46 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:46 volumio volumio[3511]: info: FusionDsp - mixtype--------------------- Hardware Jun 28 16:38:46 volumio volumio[3511]: info: Loading i18n strings for locale en Jun 28 16:38:46 volumio volumio[3511]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jun 28 16:38:46 volumio sudo[3740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 28 16:38:46 volumio sudo[3740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:46 volumio systemd[1]: Starting Logitech Media Server Daemon... Jun 28 16:38:46 volumio volumio[3511]: info: Volumio Calling Home Jun 28 16:38:46 volumio systemd[1]: Started Logitech Media Server Daemon. Jun 28 16:38:46 volumio systemd[3747]: logitechmediaserver.service: Failed to execute command: No such file or directory Jun 28 16:38:46 volumio systemd[3747]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Jun 28 16:38:46 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Jun 28 16:38:46 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Jun 28 16:38:46 volumio sudo[3740]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:46 volumio volumio[3511]: info: Preparing to generate the ALSA configuration file Jun 28 16:38:46 volumio mpd[3735]: Jun 28 16:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 28 16:38:46 volumio systemd[1]: Started Music Player Daemon. Jun 28 16:38:46 volumio sudo[3708]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:46 volumio sudo[3718]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:47 volumio volumio[3511]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 28 16:38:47 volumio volumio[3511]: info: Reading ALSA contributions from plugins. Jun 28 16:38:47 volumio volumio[3511]: info: minidlna: Found miniDLNA version 1.2.1 Jun 28 16:38:47 volumio volumio[3511]: info: logitechmediaserver started Jun 28 16:38:47 volumio volumio[3511]: info: MPD Permissions set Jun 28 16:38:47 volumio volumio[3511]: info: MPD Permissions set Jun 28 16:38:47 volumio volumio[3511]: Unable to parse: Jun 28 16:38:47 volumio volumio[3511]: Simple mixer control 'IEC958',0 Jun 28 16:38:47 volumio volumio[3511]: Capabilities: pswitch pswitch-joined Jun 28 16:38:47 volumio volumio[3511]: Playback channels: Mono Jun 28 16:38:47 volumio volumio[3511]: Mono: Playback [on] Jun 28 16:38:47 volumio volumio[3511]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 28 16:38:47 volumio volumio[3511]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 28 16:38:47 volumio volumio[3511]: Unable to parse: Jun 28 16:38:47 volumio volumio[3511]: Simple mixer control 'IEC958',0 Jun 28 16:38:47 volumio volumio[3511]: Capabilities: pswitch pswitch-joined Jun 28 16:38:47 volumio volumio[3511]: Playback channels: Mono Jun 28 16:38:47 volumio volumio[3511]: Mono: Playback [on] Jun 28 16:38:47 volumio volumio[3511]: info: VolumeController:: Volume=undefined Mute =false Jun 28 16:38:47 volumio volumio[3511]: info: CoreStateMachine::pushState Jun 28 16:38:47 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioPushState Jun 28 16:38:47 volumio volumio[3511]: info: MPD running with PID3735 Jun 28 16:38:47 volumio volumio[3511]: ,establishing connection Jun 28 16:38:47 volumio volumio[3511]: info: Volumio called home Jun 28 16:38:47 volumio volumio[3511]: info: Spotify config file written Jun 28 16:38:47 volumio sudo[3758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 28 16:38:47 volumio sudo[3758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:47 volumio systemd[1]: Stopping go-librespot Daemon... Jun 28 16:38:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jun 28 16:38:47 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Jun 28 16:38:47 volumio systemd[1]: Stopped go-librespot Daemon. Jun 28 16:38:47 volumio systemd[1]: Started go-librespot Daemon. Jun 28 16:38:47 volumio go-librespot[3760]: go-librespot daemon starting... Jun 28 16:38:47 volumio sudo[3758]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:47 volumio go-librespot[3760]: time="2025-06-28T16:38:47+08:00" level=info msg="running go-librespot 0.2.0" Jun 28 16:38:47 volumio go-librespot[3760]: time="2025-06-28T16:38:47+08:00" level=debug msg="app state loaded" Jun 28 16:38:47 volumio go-librespot[3760]: time="2025-06-28T16:38:47+08:00" level=debug msg="stored credentials not found" Jun 28 16:38:47 volumio go-librespot[3760]: time="2025-06-28T16:38:47+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 28 16:38:47 volumio volumio[3511]: error: MPD error: The expression evaluated to a falsy value: Jun 28 16:38:47 volumio volumio[3511]: assert.ok(self.idling) Jun 28 16:38:47 volumio volumio[3511]: error: The expression evaluated to a falsy value: Jun 28 16:38:47 volumio volumio[3511]: assert.ok(self.idling) Jun 28 16:38:47 volumio volumio[3511]: error: MPD error: The expression evaluated to a falsy value: Jun 28 16:38:47 volumio volumio[3511]: assert.ok(self.idling) Jun 28 16:38:47 volumio volumio[3511]: error: The expression evaluated to a falsy value: Jun 28 16:38:47 volumio volumio[3511]: assert.ok(self.idling) Jun 28 16:38:47 volumio volumio[3511]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:47 volumio volumio[3511]: info: No need to fix Spotify hosts Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 28 16:38:47 volumio volumio[3511]: error: updateQueue error: null Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:47 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:47 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 28 16:38:47 volumio volumio[3511]: info: Received Get System Info Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 16:38:47 volumio volumio[3511]: info: Discovery: Getting this device information Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:47 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:47 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:47 volumio volumio[3511]: info: Listing playlists Jun 28 16:38:47 volumio volumio[3511]: info: Discovery: adding 1ee24f5b-57d7-49a4-9b2b-8b507b2cabfd Jun 28 16:38:47 volumio volumio[3511]: info: Discovery: Found device Volumio Jun 28 16:38:47 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:47 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:48 volumio volumio[3511]: info: Starting Shairport Sync Jun 28 16:38:48 volumio volumio[3511]: info: Starting Shairport Sync Jun 28 16:38:48 volumio sudo[3784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 16:38:48 volumio sudo[3784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio volumio[3511]: info: Starting Shairport Sync Jun 28 16:38:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 28 16:38:48 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 28 16:38:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 28 16:38:48 volumio sudo[3787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 16:38:48 volumio sudo[3787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:48 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:48 volumio volumio[3511]: info: minidlna: /data/minidlna.conf written Jun 28 16:38:48 volumio volumio[3511]: info: minidlna: Starting minidlna.service Jun 28 16:38:48 volumio volumio[3511]: info: Asound.conf file unchanged, so no further update is needed Jun 28 16:38:48 volumio volumio[3511]: info: Output device has changed, restarting MPD Jun 28 16:38:48 volumio volumio[3511]: info: Output device has changed, restarting Shairport Sync Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:48 volumio sudo[3791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 28 16:38:48 volumio sudo[3794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jun 28 16:38:48 volumio sudo[3794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio sudo[3797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 28 16:38:48 volumio sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio sudo[3797]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio sudo[3800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 28 16:38:48 volumio sudo[3800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio go-librespot[3760]: time="2025-06-28T16:38:48+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 28 16:38:48 volumio go-librespot[3760]: time="2025-06-28T16:38:48+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 28 16:38:48 volumio go-librespot[3760]: time="2025-06-28T16:38:48+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 28 16:38:48 volumio sudo[3784]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio go-librespot[3760]: time="2025-06-28T16:38:48+08:00" level=info msg="zeroconf server listening on port 35841" Jun 28 16:38:48 volumio volumio[3511]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 28 16:38:48 volumio systemd[1]: Stopping Music Player Daemon... Jun 28 16:38:48 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jun 28 16:38:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 28 16:38:48 volumio sudo[3794]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 28 16:38:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 28 16:38:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 28 16:38:48 volumio systemd[1]: mpd.service: Succeeded. Jun 28 16:38:48 volumio sudo[3787]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio systemd[1]: Stopped Music Player Daemon. Jun 28 16:38:48 volumio sudo[3791]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio systemd[1]: Starting Music Player Daemon... Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 16:38:48 volumio volumio[3511]: info: MPD Permissions set Jun 28 16:38:48 volumio volumio[3511]: info: minidlna: systemctl start minidlna.service succeeded. Jun 28 16:38:48 volumio volumio[3511]: info: Completed starting Core Plugins Jun 28 16:38:48 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:48 volumio volumio[3511]: info: ----- MyVolumio plugins startup ---- Jun 28 16:38:48 volumio volumio[3511]: info: ------------------------------------------- Jun 28 16:38:48 volumio volumio[3511]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 28 16:38:48 volumio volumio[3511]: info: Shairport-Sync Started Jun 28 16:38:48 volumio volumio[3511]: Error adding Membership: Error: addMembership EINVAL Jun 28 16:38:48 volumio volumio[3511]: info: Shairport-Sync Started Jun 28 16:38:48 volumio volumio[3511]: info: Shairport-Sync Started Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 28 16:38:48 volumio sudo[3809]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 28 16:38:48 volumio sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio sudo[3809]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - undefined Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - undefined Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - undefined Jun 28 16:38:48 volumio volumio[3511]: info: Starting Shairport Sync Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::volumioGetState Jun 28 16:38:48 volumio volumio[3511]: info: CorePlayQueue::getTrack 0 Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::volumioPause Jun 28 16:38:48 volumio volumio[3511]: info: CoreStateMachine::pause Jun 28 16:38:48 volumio volumio[3511]: info: mpdhttpout --- Volumio set on pause Jun 28 16:38:48 volumio volumio[3511]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Jun 28 16:38:48 volumio sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 28 16:38:48 volumio sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 28 16:38:48 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 28 16:38:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 28 16:38:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 28 16:38:48 volumio sudo[3826]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio volumio[3511]: info: Shairport-Sync Started Jun 28 16:38:48 volumio mpd[3818]: Jun 28 16:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 28 16:38:48 volumio systemd[1]: Started Music Player Daemon. Jun 28 16:38:48 volumio sudo[3800]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio volumio[3511]: error: updateQueue error: null Jun 28 16:38:48 volumio volumio[3511]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 28 16:38:48 volumio volumio[3511]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 28 16:38:48 volumio volumio[3511]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 28 16:38:48 volumio volumio[3511]: info: camilladsp service started and running in background, instance 1 Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 28 16:38:48 volumio volumio[3511]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 28 16:38:48 volumio volumio[3511]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 28 16:38:48 volumio volumio[3511]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp loaded Jun 28 16:38:48 volumio volumio[3511]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 16:38:48 volumio sudo[3892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 28 16:38:48 volumio sudo[3892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 28 16:38:48 volumio volumio[3511]: info: Adding Signal Path Element [object Object] Jun 28 16:38:48 volumio volumio[3511]: info: Adding fusiondspeq DSP Signal Path Element Jun 28 16:38:48 volumio volumio[3511]: info: FusionDsp - ---- installed callbackRead Jun 28 16:38:48 volumio volumio[3511]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 16:38:48 volumio volumio[3511]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 28 16:38:48 volumio volumio[3511]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jun 28 16:38:48 volumio volumio[3511]: at onErrorNT (internal/child_process.js:472:16) Jun 28 16:38:48 volumio volumio[3511]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 28 16:38:48 volumio volumio[3511]: at runNextTicks (internal/process/task_queues.js:64:3) Jun 28 16:38:48 volumio volumio[3511]: at listOnTimeout (internal/timers.js:526:9) Jun 28 16:38:48 volumio volumio[3511]: at processTimers (internal/timers.js:500:7) { Jun 28 16:38:48 volumio volumio[3511]: errno: -2, Jun 28 16:38:48 volumio volumio[3511]: code: 'ENOENT', Jun 28 16:38:48 volumio volumio[3511]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 28 16:38:48 volumio volumio[3511]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 28 16:38:48 volumio volumio[3511]: spawnargs: [ Jun 28 16:38:48 volumio volumio[3511]: '-p', Jun 28 16:38:48 volumio volumio[3511]: 9876, Jun 28 16:38:48 volumio volumio[3511]: '-o', Jun 28 16:38:48 volumio volumio[3511]: '/tmp/camilladsp.log', Jun 28 16:38:48 volumio volumio[3511]: '-l', Jun 28 16:38:48 volumio volumio[3511]: 'warn', Jun 28 16:38:48 volumio volumio[3511]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 28 16:38:48 volumio volumio[3511]: ] Jun 28 16:38:48 volumio volumio[3511]: } Jun 28 16:38:48 volumio volumio[3511]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 16:38:48 volumio systemd[1]: Started FusionDsp Daemon. Jun 28 16:38:48 volumio sudo[3892]: pam_unix(sudo:session): session closed for user root Jun 28 16:38:48 volumio sudo[3904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 16:37 Jun 28 16:38:48 volumio sudo[3904]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"