-- Logs begin at Tue 2025-05-13 12:22:17 -03, end at Mon 2025-05-19 19:05:26 -03. -- May 19 19:04:02 volumio volumio-remote-updater[576]: [2025-05-19 22:04:02] [connect] Successful connection May 19 19:04:14 volumio volumio-remote-updater[576]: [2025-05-19 22:04:14] [connect] Successful connection May 19 19:04:29 volumio volumio-remote-updater[576]: [2025-05-19 22:04:29] [connect] Successful connection May 19 19:04:30 volumio ntpd[765]: Soliciting pool server 2001:1328:54::34 May 19 19:04:31 volumio volumio[876]: info: Checking if install.sh is present May 19 19:04:31 volumio volumio[876]: info: Executing install.sh May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 1 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 2 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 3 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 4 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 6 May 19 19:04:31 volumio volumio-remote-updater[576]: [2025-05-19 22:04:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747692269 101 May 19 19:04:31 volumio volumio[876]: 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 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 8 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 9 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 10 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 11 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 12 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 13 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 14 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 15 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 16 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 17 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 18 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 19 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 20 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 21 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 22 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 23 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 24 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 25 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 26 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 27 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 28 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 29 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 30 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 31 May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.7 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 32 May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 May 19 19:04:31 volumio volumio[876]: info: Received Get System Info May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 19:04:31 volumio volumio[876]: info: Discovery: Getting this device information May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetVisibleSources May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 19 19:04:31 volumio volumio[876]: info: Received Get System Info May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 19:04:31 volumio volumio[876]: info: Discovery: Getting this device information May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:31 volumio volumio[876]: info: Listing playlists May 19 19:04:31 volumio volumio[876]: info: CoreCommandRouter::volumioGetQueue May 19 19:04:31 volumio volumio[876]: info: CoreStateMachine::getQueue May 19 19:04:31 volumio volumio[876]: info: CorePlayQueue::getQueue May 19 19:04:32 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:32 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 19 19:04:32 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:32 volumio volumio[876]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 May 19 19:04:32 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:32 volumio sudo[8543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh May 19 19:04:32 volumio sudo[8543]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:04:32 volumio volumio[876]: info: Installing Go-librespot May 19 19:04:32 volumio volumio[876]: info: Installing Go-librespot May 19 19:04:34 volumio volumio[876]: info: Checking old vollibrespot installs May 19 19:04:34 volumio volumio[876]: info: Checking old vollibrespot installs May 19 19:04:34 volumio systemd[1]: Reloading. May 19 19:04:34 volumio volumio[876]: info: May 19 19:04:34 volumio volumio[876]: Selecting previously unselected package python3-asn1crypto. May 19 19:04:34 volumio volumio[876]: info: May 19 19:04:34 volumio volumio[876]: Selecting previously unselected package python3-asn1crypto. May 19 19:04:34 volumio volumio[876]: info: Preparing to unpack .../35-python3-asn1crypto_0.24.0-1_all.deb ... May 19 19:04:34 volumio volumio[876]: info: Unpacking python3-asn1crypto (0.24.0-1) ... May 19 19:04:34 volumio volumio[876]: info: Preparing to unpack .../35-python3-asn1crypto_0.24.0-1_all.deb ... May 19 19:04:34 volumio volumio[876]: info: Unpacking python3-asn1crypto (0.24.0-1) ... May 19 19:04:34 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:35 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. May 19 19:04:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:37 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:37 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:38 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:38 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:39 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:04:40 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:40 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:41 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:41 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 19 19:04:42 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 19 19:04:42 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 19 19:04:49 volumio systemd[1]: Reloading. May 19 19:04:49 volumio volumio[876]: info: Selecting previously unselected package python3-cffi-backend. May 19 19:04:49 volumio volumio[876]: info: Selecting previously unselected package python3-cffi-backend. May 19 19:04:49 volumio volumio[876]: info: Preparing to unpack .../36-python3-cffi-backend_1.12.2-1_armhf.deb ... May 19 19:04:49 volumio volumio[876]: info: Unpacking python3-cffi-backend (1.12.2-1) ... May 19 19:04:49 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. May 19 19:04:49 volumio volumio[876]: info: Preparing to unpack .../36-python3-cffi-backend_1.12.2-1_armhf.deb ... May 19 19:04:49 volumio volumio[876]: info: Unpacking python3-cffi-backend (1.12.2-1) ... May 19 19:04:49 volumio sudo[8543]: pam_unix(sudo:session): session closed for user root May 19 19:04:49 volumio volumio[876]: info: Install script completed May 19 19:04:49 volumio volumio[876]: info: Adding reference to registry May 19 19:04:49 volumio volumio[876]: info: Done installing plugin. May 19 19:04:49 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 19 19:04:49 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 19 19:04:50 volumio volumio[876]: info: Folder /tmp/plugins removed May 19 19:04:50 volumio volumio[876]: info: Folder /tmp/downloaded_plugin.zip removed May 19 19:04:57 volumio volumio[876]: info: Enabling plugin fusiondsp May 19 19:04:57 volumio volumio[876]: info: Enabling plugin spop May 19 19:04:57 volumio volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin May 19 19:04:57 volumio volumio[876]: info: Loading plugin "fusiondsp"... May 19 19:05:01 volumio volumio-remote-updater[576]: [2025-05-19 22:05:01] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 19 19:05:06 volumio volumio-remote-updater[576]: [2025-05-19 22:05:06] [connect] Successful connection May 19 19:05:14 volumio volumio[876]: info: Loading plugin "spop"... May 19 19:05:15 volumio volumio[876]: info: Preparing to generate the ALSA configuration file May 19 19:05:15 volumio volumio[876]: info: PLUGIN START: spop May 19 19:05:15 volumio volumio[876]: info: Creating Spotify config file May 19 19:05:15 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:15 volumio volumio[876]: info: Done. May 19 19:05:15 volumio volumio[876]: info: Folder /data/temp removed May 19 19:05:15 volumio volumio[876]: info: Spotify config file written May 19 19:05:15 volumio volumio[876]: info: No need to fix Spotify hosts May 19 19:05:15 volumio volumio[876]: info: Asound.conf file unchanged, so no further update is needed May 19 19:05:15 volumio volumio[876]: info: Output device has changed, restarting MPD May 19 19:05:15 volumio sudo[8746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 19 19:05:15 volumio sudo[8746]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:05:15 volumio volumio[876]: info: Output device has changed, restarting Shairport Sync May 19 19:05:15 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:15 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 19:05:15 volumio sudo[8750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 19 19:05:15 volumio sudo[8750]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:05:15 volumio systemd[1]: Started go-librespot Daemon. May 19 19:05:15 volumio sudo[8753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 19 19:05:15 volumio sudo[8750]: pam_unix(sudo:session): session closed for user root May 19 19:05:15 volumio sudo[8753]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:05:15 volumio go-librespot[8751]: go-librespot daemon starting... May 19 19:05:16 volumio sudo[8746]: pam_unix(sudo:session): session closed for user root May 19 19:05:16 volumio volumio[876]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 19 19:05:16 volumio volumio[876]: info: PLUGIN START: fusiondsp May 19 19:05:16 volumio systemd[1]: Stopping Music Player Daemon... May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=info msg="running go-librespot 0.2.0" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=debug msg="no app state found" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=debug msg="stored credentials not found" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=info msg="generated new device id: 6ac19f733cae4364aeae2081a4f913e882595378" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=info msg="api server listening on 127.0.0.1:9879" May 19 19:05:16 volumio volumio[876]: info: Loading i18n strings for locale es May 19 19:05:16 volumio systemd[1]: mpd.service: Succeeded. May 19 19:05:16 volumio systemd[1]: Stopped Music Player Daemon. May 19 19:05:16 volumio systemd[1]: Starting Music Player Daemon... May 19 19:05:16 volumio sudo[8765]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 19 19:05:16 volumio sudo[8765]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:05:16 volumio sudo[8765]: pam_unix(sudo:session): session closed for user root May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 19 19:05:16 volumio go-librespot[8751]: time="2025-05-19T19:05:16-03:00" level=info msg="zeroconf server listening on port 34485" May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: FusionDsp - mixtype--------------------- Hardware May 19 19:05:16 volumio volumio[876]: info: Preparing to generate the ALSA configuration file May 19 19:05:16 volumio volumio[876]: info: Done. May 19 19:05:16 volumio volumio[876]: info: Selecting previously unselected package python3-crypto. May 19 19:05:16 volumio volumio[876]: info: Preparing to unpack .../37-python3-crypto_2.6.1-9+b1_armhf.deb ... May 19 19:05:16 volumio volumio[876]: info: Unpacking python3-crypto (2.6.1-9+b1) ... May 19 19:05:16 volumio volumio[876]: info: Selecting previously unselected package python3-crypto. May 19 19:05:16 volumio volumio[876]: info: Preparing to unpack .../37-python3-crypto_2.6.1-9+b1_armhf.deb ... May 19 19:05:16 volumio volumio[876]: info: Unpacking python3-crypto (2.6.1-9+b1) ... May 19 19:05:16 volumio volumio[876]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 19 19:05:16 volumio volumio[876]: info: Reading ALSA contributions from plugins. May 19 19:05:16 volumio volumio[876]: info: MPD Permissions set May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 19 19:05:16 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:16 volumio volumio[876]: info: Starting Shairport Sync May 19 19:05:16 volumio sudo[8775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 19 19:05:16 volumio sudo[8775]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:05:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 19 19:05:16 volumio systemd[1]: shairport-sync.service: Succeeded. May 19 19:05:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 19 19:05:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 19 19:05:16 volumio sudo[8775]: pam_unix(sudo:session): session closed for user root May 19 19:05:16 volumio volumio[876]: info: Shairport-Sync Started May 19 19:05:17 volumio volumio[876]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 19 19:05:17 volumio volumio[876]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 19 19:05:17 volumio volumio[876]: info: CoreCommandRouter::volumioGetState May 19 19:05:18 volumio volumio-remote-updater[576]: [2025-05-19 22:05:18] [connect] Successful connection May 19 19:05:21 volumio volumio[876]: info: camilladsp service started and running in background, instance 1 May 19 19:05:21 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 19 19:05:21 volumio volumio[876]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 19 19:05:21 volumio volumio[876]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 19 19:05:21 volumio volumio[876]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 19 19:05:21 volumio volumio[876]: info: FusionDsp loaded May 19 19:05:21 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 19:05:21 volumio sudo[8791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 19 19:05:21 volumio sudo[8791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 19 19:05:21 volumio volumio[876]: info: FusionDsp - Reporting Fusion DSP Enabled May 19 19:05:21 volumio volumio[876]: info: Adding Signal Path Element [object Object] May 19 19:05:21 volumio volumio[876]: info: Adding fusiondspeq DSP Signal Path Element May 19 19:05:21 volumio volumio[876]: info: FusionDsp - ---- installed callbackRead May 19 19:05:21 volumio volumio[876]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 19:05:21 volumio volumio[876]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 19 19:05:21 volumio volumio[876]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 19 19:05:21 volumio volumio[876]: at onErrorNT (internal/child_process.js:465:16) May 19 19:05:21 volumio volumio[876]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 19 19:05:21 volumio volumio[876]: errno: -2, May 19 19:05:21 volumio volumio[876]: code: 'ENOENT', May 19 19:05:21 volumio volumio[876]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 19 19:05:21 volumio volumio[876]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 19 19:05:21 volumio volumio[876]: spawnargs: [ May 19 19:05:21 volumio volumio[876]: '-p', May 19 19:05:21 volumio volumio[876]: 9876, May 19 19:05:21 volumio volumio[876]: '-o', May 19 19:05:21 volumio volumio[876]: '/tmp/camilladsp.log', May 19 19:05:21 volumio volumio[876]: '-l', May 19 19:05:21 volumio volumio[876]: 'warn', May 19 19:05:21 volumio volumio[876]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 19 19:05:21 volumio volumio[876]: ] May 19 19:05:21 volumio volumio[876]: } May 19 19:05:21 volumio volumio[876]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 19:05:21 volumio sudo[8791]: pam_unix(sudo:session): session closed for user root May 19 19:05:26 volumio sudo[8804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 19:04 May 19 19:05:26 volumio sudo[8804]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"