-- Logs begin at Thu 2025-04-17 20:34:45 CEST, end at Thu 2025-04-17 21:12:33 CEST. -- Apr 17 21:11:08 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:14 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:16 volumio volumio-remote-updater[612]: [2025-04-17 21:11:16] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Apr 17 21:11:17 volumio go-librespot[1256]: time="2025-04-17T21:11:17+02:00" level=trace msg="sent dealer ping" Apr 17 21:11:17 volumio go-librespot[1256]: time="2025-04-17T21:11:17+02:00" level=trace msg="received dealer pong" Apr 17 21:11:20 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:21 volumio volumio-remote-updater[612]: [2025-04-17 21:11:21] [connect] Successful connection Apr 17 21:11:26 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:34 volumio volumio-remote-updater[612]: [2025-04-17 21:11:34] [connect] Successful connection Apr 17 21:11:34 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:39 volumio volumio[1009]: info: Checking if install.sh is present Apr 17 21:11:39 volumio volumio[1009]: info: Executing install.sh Apr 17 21:11:39 volumio volumio[1009]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Apr 17 21:11:39 volumio volumio[1009]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Apr 17 21:11:39 volumio volumio[1009]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Apr 17 21:11:39 volumio sudo[19092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 17 21:11:39 volumio volumio[1009]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Apr 17 21:11:39 volumio sudo[19092]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:11:39 volumio volumio[1009]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Apr 17 21:11:39 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:39 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:39 volumio volumio[1009]: info: creating filters folder and copying demo filters Apr 17 21:11:39 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:39 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:39 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:39 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 17 21:11:40 volumio volumio[1009]: info: copying demo flters Apr 17 21:11:40 volumio volumio[1009]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 17 21:11:40 volumio volumio[1009]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:40 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:40 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:41 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:41 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:41 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:41 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:41 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:41 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:41 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:41 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:41 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:41 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:41 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:41 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:41 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:42 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:42 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:42 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:42 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:42 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:42 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:42 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:42 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:42 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:42 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:42 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:42 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 21:11:43 volumio volumio[1009]: info: Listing playlists Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:43 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:43 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:45 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:45 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:45 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:45 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:45 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:45 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:45 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:45 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:45 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:45 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:45 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:45 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:46 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:46 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:46 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:46 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:46 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:46 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:46 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:46 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:46 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:46 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:46 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:46 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:47 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:47 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:47 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:47 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:47 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:47 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:47 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:47 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:47 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:47 volumio go-librespot[1256]: time="2025-04-17T21:11:47+02:00" level=trace msg="sent dealer ping" Apr 17 21:11:47 volumio go-librespot[1256]: time="2025-04-17T21:11:47+02:00" level=trace msg="received dealer pong" Apr 17 21:11:47 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:47 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:47 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:47 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:48 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:48 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:48 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:48 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:49 volumio volumio-remote-updater[612]: [2025-04-17 21:11:49] [connect] Successful connection Apr 17 21:11:49 volumio volumio-remote-updater[612]: [2025-04-17 21:11:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744917109 101 Apr 17 21:11:49 volumio volumio[1009]: 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: 11 Apr 17 21:11:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:50 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:50 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:50 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:50 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/ Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/2XEQ15/ Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/PEQ/ Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/convfir/ Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/convfir/kef.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/convfir/test.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/EQ15/ Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/EQ15/.Bass-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/EQ15/.Classic-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/EQ15/.Voice-FusionDsp.json Apr 17 21:11:50 volumio volumio[1009]: info: ./presets/EQ15/.Rock-FusionDsp.json Apr 17 21:11:50 volumio sudo[19376]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 17 21:11:50 volumio sudo[19376]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:11:50 volumio volumio[1009]: info: Installing/fusiondsp dependencies Apr 17 21:11:50 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:50 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:50 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:50 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:50 volumio volumio[1009]: info: Hit:1 http://archive.raspberrypi.org/debian buster InRelease Apr 17 21:11:50 volumio volumio[1009]: info: Hit:2 http://raspbian.raspberrypi.org/raspbian buster InRelease Apr 17 21:11:50 volumio volumio[1009]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Apr 17 21:11:50 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:50 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:50 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:50 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:51 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:51 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:51 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:51 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:51 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:51 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:51 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:51 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:51 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:51 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:51 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:51 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:52 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:52 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:52 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:52 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:52 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:52 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:52 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:52 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:52 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:52 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:52 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:52 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:53 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:53 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:53 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:53 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:53 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:53 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:53 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:53 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:53 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:53 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:53 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:53 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:53 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:54 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:54 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: Reading package lists... Apr 17 21:11:54 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:54 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:54 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: Building dependency tree... Apr 17 21:11:54 volumio volumio[1009]: info: Reading state information... Apr 17 21:11:54 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:54 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:54 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:54 volumio volumio[1009]: info: All packages are up to date. Apr 17 21:11:54 volumio sudo[19376]: pam_unix(sudo:session): session closed for user root Apr 17 21:11:54 volumio sudo[19673]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 17 21:11:54 volumio sudo[19673]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:11:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:56 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:56 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:56 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:56 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:56 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:56 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:56 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:56 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:56 volumio volumio[1009]: info: Reading package lists... Apr 17 21:11:56 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:56 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:56 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:56 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:57 volumio volumio[1009]: info: Building dependency tree... Apr 17 21:11:57 volumio volumio[1009]: info: Reading state information... Apr 17 21:11:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:58 volumio volumio[1009]: info: The following packages were automatically installed and are no longer required: Apr 17 21:11:58 volumio volumio[1009]: info: keyboard-configuration libgbm1 libwayland-server0 libxxf86dga1 Apr 17 21:11:58 volumio volumio[1009]: info: Use 'sudo apt autoremove' to remove them. Apr 17 21:11:58 volumio volumio[1009]: info: The following additional packages will be installed: Apr 17 21:11:58 volumio volumio[1009]: info: build-essential bzip2 cpp cpp-8 dh-python dpkg-dev fakeroot g++ g++-8 gcc Apr 17 21:11:58 volumio volumio[1009]: info: gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Apr 17 21:11:58 volumio volumio[1009]: info: libalgorithm-merge-perl libasan5 libcc1-0 libexpat1-dev libfakeroot Apr 17 21:11:58 volumio volumio[1009]: info: libgcc-8-dev libisl19 libmpc3 libmpfr6 libpython3-dev libpython3.7 Apr 17 21:11:58 volumio volumio[1009]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Apr 17 21:11:58 volumio volumio[1009]: info: python3-asn1crypto python3-async-timeout python3-attr python3-cffi-backend Apr 17 21:11:58 volumio volumio[1009]: info: python3-crypto python3-cryptography python3-dbus python3-dev Apr 17 21:11:58 volumio volumio[1009]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt Apr 17 21:11:58 volumio volumio[1009]: info: python3-multidict python3-secretstorage python3-setuptools python3-wheel Apr 17 21:11:58 volumio volumio[1009]: info: python3-xdg python3-yarl python3.7-dev Apr 17 21:11:58 volumio volumio[1009]: info: Suggested packages: Apr 17 21:11:58 volumio volumio[1009]: info: bzip2-doc cpp-doc gcc-8-locales debian-keyring gcc-8-doc libstdc++6-8-dbg Apr 17 21:11:58 volumio volumio[1009]: info: gcc-multilib autoconf automake libtool flex bison gdb gcc-doc libgcc1-dbg Apr 17 21:11:58 volumio volumio[1009]: info: libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg liblsan0-dbg Apr 17 21:11:58 volumio volumio[1009]: info: libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg libstdc++-8-doc Apr 17 21:11:58 volumio volumio[1009]: info: make-doc ed diffutils-doc python-attr-doc python-crypto-doc Apr 17 21:11:58 volumio volumio[1009]: info: python-cryptography-doc python3-cryptography-vectors python-dbus-doc Apr 17 21:11:58 volumio volumio[1009]: info: python3-dbus-dbg gnome-keyring libkf5wallet-bin gir1.2-gnomekeyring-1.0 Apr 17 21:11:58 volumio volumio[1009]: info: python-secretstorage-doc python-setuptools-doc Apr 17 21:11:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:59 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:11:59 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:59 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:59 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:59 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:59 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:59 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:59 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:59 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:59 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:59 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:11:59 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:11:59 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:00 volumio volumio[1009]: info: The following NEW packages will be installed: Apr 17 21:12:00 volumio volumio[1009]: info: build-essential bzip2 cpp cpp-8 dh-python dpkg-dev fakeroot g++ g++-8 gcc Apr 17 21:12:00 volumio volumio[1009]: info: gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Apr 17 21:12:00 volumio volumio[1009]: info: libalgorithm-merge-perl libasan5 libcc1-0 libexpat1-dev libfakeroot Apr 17 21:12:00 volumio volumio[1009]: info: libgcc-8-dev libisl19 libmpc3 libmpfr6 libpython3-dev libpython3.7 Apr 17 21:12:00 volumio volumio[1009]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Apr 17 21:12:00 volumio volumio[1009]: info: python3-aiohttp python3-asn1crypto python3-async-timeout python3-attr Apr 17 21:12:00 volumio volumio[1009]: info: python3-cffi-backend python3-crypto python3-cryptography python3-dbus Apr 17 21:12:00 volumio volumio[1009]: info: python3-dev python3-entrypoints python3-gi python3-keyring Apr 17 21:12:00 volumio volumio[1009]: info: python3-keyrings.alt python3-multidict python3-pip python3-secretstorage Apr 17 21:12:00 volumio volumio[1009]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Apr 17 21:12:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:01 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:01 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:01 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:01 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:01 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:01 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:01 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:01 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:01 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:01 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:01 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:01 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:02 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:02 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:02 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:02 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:02 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:02 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:02 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:02 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:02 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:02 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:02 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:02 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:03 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:03 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:04 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:04 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:04 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:04 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:04 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:12:04 volumio volumio[1009]: info: Starting Uninstall of plugin audio_interface - fusiondsp Apr 17 21:12:04 volumio volumio[1009]: info: Uninstalling plugin fusiondsp Apr 17 21:12:04 volumio volumio[1009]: info: FusionDsp - Stopping FusionDsp service Apr 17 21:12:04 volumio volumio[1009]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 21:12:04 volumio volumio[1009]: TypeError: Cannot read property 'stop' of null Apr 17 21:12:04 volumio volumio[1009]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Apr 17 21:12:04 volumio volumio[1009]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 17 21:12:04 volumio volumio[1009]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Apr 17 21:12:04 volumio volumio[1009]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Apr 17 21:12:04 volumio volumio[1009]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Apr 17 21:12:04 volumio volumio[1009]: at Socket.emit (events.js:315:20) Apr 17 21:12:04 volumio volumio[1009]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 17 21:12:04 volumio volumio[1009]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 17 21:12:04 volumio volumio[1009]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 21:12:05 volumio sudo[19882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-17 21:11 Apr 17 21:12:05 volumio sudo[19882]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:05 volumio sudo[19882]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:05 volumio volumio-remote-updater[612]: [2025-04-17 21:12:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 17 21:12:05 volumio volumio-remote-updater[612]: [2025-04-17 21:12:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 17 21:12:05 volumio go-librespot[1256]: time="2025-04-17T21:12:05+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Apr 17 21:12:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 17 21:12:05 volumio sudo[19092]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:10 volumio volumio-remote-updater[612]: [2025-04-17 21:12:10] [info] asio async_connect error: system:111 (Connection refused) Apr 17 21:12:10 volumio volumio-remote-updater[612]: [2025-04-17 21:12:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 21:12:10 volumio volumio-remote-updater[612]: [2025-04-17 21:12:10] [error] handle_connect error: Underlying Transport Error Apr 17 21:12:13 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:12:17 volumio go-librespot[1256]: time="2025-04-17T21:12:17+02:00" level=trace msg="received accesspoint ping" Apr 17 21:12:17 volumio go-librespot[1256]: time="2025-04-17T21:12:17+02:00" level=trace msg="received accesspoint pong ack" Apr 17 21:12:17 volumio go-librespot[1256]: time="2025-04-17T21:12:17+02:00" level=trace msg="sent dealer ping" Apr 17 21:12:17 volumio go-librespot[1256]: time="2025-04-17T21:12:17+02:00" level=trace msg="received dealer pong" Apr 17 21:12:17 volumio volumio-remote-updater[612]: [2025-04-17 21:12:17] [info] asio async_connect error: system:111 (Connection refused) Apr 17 21:12:17 volumio volumio-remote-updater[612]: [2025-04-17 21:12:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 21:12:17 volumio volumio-remote-updater[612]: [2025-04-17 21:12:17] [error] handle_connect error: Underlying Transport Error Apr 17 21:12:20 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:12:25 volumio sudo[19673]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 17 21:12:25 volumio systemd[1]: Started dynamicswap service. Apr 17 21:12:25 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 17 21:12:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 17 21:12:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 17 21:12:25 volumio systemd[1]: Started dynamicswap service. Apr 17 21:12:25 volumio systemd[1]: Stopped Volumio Backend Module. Apr 17 21:12:25 volumio systemd[1]: Started Volumio Backend Module. Apr 17 21:12:25 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 17 21:12:26 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:26 volumio volumio[20273]: info: ----- Volumio3 ---- Apr 17 21:12:26 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:26 volumio volumio[20273]: info: ----- System startup ---- Apr 17 21:12:26 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:26 volumio volumio[20273]: info: MYVOLUMIO Environment detected Apr 17 21:12:26 volumio volumio[20273]: info: Plugin folders cleanup Apr 17 21:12:26 volumio volumio[20273]: info: Scanning into folder /volumio/app/plugins/ Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category audio_interface Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category miscellanea Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category music_service Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category plugins.json Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category system_controller Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category user_interface Apr 17 21:12:26 volumio volumio[20273]: info: Scanning into folder /data/plugins/ Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category audio_interface Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category music_service Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category system_hardware Apr 17 21:12:26 volumio volumio[20273]: info: Scanning category user_interface Apr 17 21:12:26 volumio volumio[20273]: info: Plugin folders cleanup completed Apr 17 21:12:26 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:26 volumio volumio[20273]: info: ----- Core plugins startup ---- Apr 17 21:12:26 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:26 volumio volumio[20273]: info: Loading plugins from folder /volumio/app/plugins/ Apr 17 21:12:26 volumio volumio[20273]: info: Adding plugin upnp to MyMusic Plugins Apr 17 21:12:26 volumio volumio[20273]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 17 21:12:26 volumio volumio[20273]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 17 21:12:26 volumio volumio[20273]: info: Loading plugins from folder /data/plugins/ Apr 17 21:12:26 volumio volumio[20273]: info: Loading plugin "system"... Apr 17 21:12:26 volumio volumio[20273]: info: Loading plugin "appearance"... Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "network"... Apr 17 21:12:27 volumio volumio[20273]: info: Refreshing Cached IP Addresses Apr 17 21:12:27 volumio sudo[20314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 21:12:27 volumio sudo[20314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:27 volumio sudo[20314]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:27 volumio sudo[20316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 21:12:27 volumio sudo[20316]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:27 volumio sudo[20316]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "services"... Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "alsa_controller"... Apr 17 21:12:27 volumio sudo[20325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 17 21:12:27 volumio sudo[20325]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:27 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "wizard"... Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "networkfs"... Apr 17 21:12:27 volumio volumio[20273]: info: Cannot mount NAS nas1 at system boot, trial number 1 ,retrying in 5 seconds Apr 17 21:12:27 volumio volumio[20273]: info: Cannot mount NAS nas2 at system boot, trial number 1 ,retrying in 5 seconds Apr 17 21:12:27 volumio volumio[20273]: info: Starting Udev Watcher for removable devices Apr 17 21:12:27 volumio volumio[20273]: info: Ignoring mount for partition: boot Apr 17 21:12:27 volumio volumio[20273]: info: Ignoring mount for partition: volumio Apr 17 21:12:27 volumio volumio[20273]: info: Ignoring mount for partition: volumio_data Apr 17 21:12:27 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "volumio_command_line_client"... Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "upnp"... Apr 17 21:12:27 volumio volumio[20273]: info: [1744917147705] Starting Upmpd Daemon Apr 17 21:12:27 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "my_music"... Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "mpd"... Apr 17 21:12:27 volumio volumio-remote-updater[612]: [2025-04-17 21:12:27] [connect] Successful connection Apr 17 21:12:27 volumio volumio[20273]: info: Loading plugin "upnp_browser"... Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "alarm-clock"... Apr 17 21:12:28 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "airplay_emulation"... Apr 17 21:12:28 volumio volumio[20273]: info: Starting Shairport Sync Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "last_100"... Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "webradio"... Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "i2s_dacs"... Apr 17 21:12:28 volumio volumio[20273]: info: I2S DAC not set, start Auto-detection Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "volumiodiscovery"... Apr 17 21:12:28 volumio volumio[20273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 21:12:28 volumio volumio[20273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 21:12:28 volumio node[20273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 21:12:28 volumio volumio[20273]: *** WARNING *** For more information see Apr 17 21:12:28 volumio volumio[20273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 21:12:28 volumio volumio[20273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 21:12:28 volumio volumio[20273]: *** WARNING *** For more information see Apr 17 21:12:28 volumio node[20273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 21:12:28 volumio node[20273]: *** WARNING *** For more information see Apr 17 21:12:28 volumio node[20273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 21:12:28 volumio node[20273]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 21:12:28 volumio node[20273]: *** WARNING *** For more information see Apr 17 21:12:28 volumio volumio[20273]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 17 21:12:28 volumio volumio[20273]: info: Discovery: Started advertising with name: Volumio Apr 17 21:12:28 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "spop"... Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "outputs"... Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "albumart"... Apr 17 21:12:28 volumio volumio[20273]: info: Plugin example_plugin is not enabled Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "inputs"... Apr 17 21:12:28 volumio volumio[20273]: info: Loading plugin "updater_comm"... Apr 17 21:12:29 volumio volumio[20273]: info: Plugin mpdemulation is not enabled Apr 17 21:12:29 volumio volumio[20273]: info: Loading plugin "rest_api"... Apr 17 21:12:29 volumio volumio[20273]: info: Loading plugin "websocket"... Apr 17 21:12:29 volumio volumio[20273]: info: Starting Socket.io Server version 2.3.0 Apr 17 21:12:29 volumio volumio[20273]: info: Loading plugin "fusiondsp"... Apr 17 21:12:29 volumio volumio[20273]: Forking 3 albumart workers Apr 17 21:12:29 volumio volumio[20273]: info: Applying required configuration parameters for plugin fusiondsp Apr 17 21:12:29 volumio volumio[20273]: info: Loading plugin "personal_radio"... Apr 17 21:12:29 volumio volumio[20273]: info: ControllerPersonalRadio::constructor Apr 17 21:12:29 volumio volumio[20273]: Starting albumart workers Apr 17 21:12:29 volumio volumio[20273]: info: Loading plugin "podcast"... Apr 17 21:12:29 volumio volumio[20273]: Starting albumart workers Apr 17 21:12:29 volumio volumio[20273]: Starting albumart workers Apr 17 21:12:29 volumio volumio[20273]: info: ControllerPodcast::constructor Apr 17 21:12:30 volumio volumio[20273]: info: Loading plugin "rad357pl"... Apr 17 21:12:30 volumio volumio[20273]: info: Applying required configuration parameters for plugin rad357pl Apr 17 21:12:30 volumio volumio[20273]: info: [1744917150207] [rad357pl] API delay: 5 Apr 17 21:12:30 volumio volumio[20273]: info: Loading plugin "radio_paradise"... Apr 17 21:12:30 volumio volumio[20273]: info: Applying required configuration parameters for plugin radio_paradise Apr 17 21:12:30 volumio volumio[20273]: info: [1744917150333] [RadioParadise] API delay: 5 Apr 17 21:12:30 volumio volumio[20273]: info: Loading plugin "mpd_oled"... Apr 17 21:12:30 volumio volumio[20273]: info: Applying required configuration parameters for plugin mpd_oled Apr 17 21:12:30 volumio volumio[20273]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Apr 17 21:12:30 volumio volumio[20273]: info: Loading i18n strings for locale it Apr 17 21:12:30 volumio volumio[20273]: Updating browse sources language Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::initPlayerControls Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: Express server listening on port 3000 Apr 17 21:12:30 volumio volumio[20273]: [Metrics] WebUI: 4s 376.13ms Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::resetVolumioState Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::getcurrentVolume Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: Volumio Network Manager: Network status updated: 2 Apr 17 21:12:30 volumio volumio-remote-updater[612]: [2025-04-17 21:12:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744917147 101 Apr 17 21:12:30 volumio volumio[20273]: 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: 1 Apr 17 21:12:30 volumio volumio[20273]: verbose: New Socket.io Connection to 192.168.10.223 from 192.168.10.228 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:30 volumio volumio[20273]: info: Reloading queue from file Apr 17 21:12:30 volumio volumio[20273]: info: VolumeController:: Volume=34 Mute =false Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::pushState Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioPushState Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::updateTrackBlock Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrackBlock Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::setRepeat null single undefined Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::pushState Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioPushState Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::setRandom null Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::pushState Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioPushState Apr 17 21:12:30 volumio volumio[20273]: info: Setting Device type: Raspberry PI Apr 17 21:12:30 volumio volumio[20273]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 17 21:12:30 volumio volumio[20273]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Apr 17 21:12:30 volumio volumio[20273]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:30 volumio volumio[20273]: info: Completed loading Core Plugins Apr 17 21:12:30 volumio volumio[20273]: info: Preparing to generate the ALSA configuration file Apr 17 21:12:30 volumio volumio[20273]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 21:12:30 volumio volumio[20273]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 17 21:12:30 volumio volumio[20273]: info: Reading ALSA contributions from plugins. Apr 17 21:12:30 volumio volumio[20273]: info: VolumeController:: Volume=34 Mute =false Apr 17 21:12:30 volumio volumio[20273]: info: CoreStateMachine::pushState Apr 17 21:12:30 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::volumioPushState Apr 17 21:12:30 volumio volumio[20273]: info: Asound.conf file unchanged, so no further update is needed Apr 17 21:12:30 volumio volumio[20273]: info: Output device has changed, restarting MPD Apr 17 21:12:30 volumio sudo[20414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 21:12:30 volumio sudo[20414]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:30 volumio sudo[20414]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:30 volumio volumio[20273]: info: Output device has changed, restarting Shairport Sync Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:30 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:30 volumio sudo[20417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 21:12:30 volumio sudo[20417]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:30 volumio systemd[1]: Stopping MPD OLED Plugin... Apr 17 21:12:30 volumio systemd[1]: mpd_oled_plugin.service: Main process exited, code=killed, status=15/TERM Apr 17 21:12:30 volumio volumio[20273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 21:12:30 volumio volumio[20273]: info: ___________ START PLUGINS ___________ Apr 17 21:12:30 volumio volumio[20273]: info: ControllerMpd::onStart: Initializing MPD Apr 17 21:12:30 volumio volumio[20273]: info: Creating MPD Configuration file Apr 17 21:12:30 volumio systemd[1]: mpd_oled_plugin.service: Succeeded. Apr 17 21:12:30 volumio systemd[1]: Stopped MPD OLED Plugin. Apr 17 21:12:30 volumio systemd[1]: Stopping Music Player Daemon... Apr 17 21:12:31 volumio sudo[20423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 21:12:31 volumio sudo[20423]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio sudo[20423]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio systemd[1]: mpd.service: Succeeded. Apr 17 21:12:31 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 21:12:31 volumio sudo[20426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 21:12:31 volumio sudo[20426]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio systemd[1]: Starting Music Player Daemon... Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151027] CoreMusicLibrary::Adding element Server multimediali Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:31 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 17 21:12:31 volumio systemd[1]: mpd.service: Succeeded. Apr 17 21:12:31 volumio volumio[20273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 21:12:31 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:31 volumio systemd[1]: Starting Music Player Daemon... Apr 17 21:12:31 volumio volumio[20273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151119] CoreMusicLibrary::Adding element Last_100 Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151121] CoreMusicLibrary::Adding element Webradio Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 21:12:31 volumio volumio[20273]: info: Initializing BBC Radios Apr 17 21:12:31 volumio sudo[20432]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 21:12:31 volumio sudo[20432]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio sudo[20432]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: Creating Spotify config file Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: Loading i18n strings for locale it Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: FusionDsp - mixtype--------------------- Hardware Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151278] CoreMusicLibrary::Adding element Personal Radio Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Personal Radio Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151285] CoreMusicLibrary::Adding element Podcast Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Personal Radio Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Podcast Apr 17 21:12:31 volumio sudo[20325]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151289] CoreMusicLibrary::Adding element RADIO 357 Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Personal Radio Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Podcast Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source RADIO 357 Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:31 volumio volumio[20273]: info: [1744917151293] CoreMusicLibrary::Adding element Radio Paradise Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Personal Radio Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Podcast Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source RADIO 357 Apr 17 21:12:31 volumio volumio[20273]: Cannot find translation for source Radio Paradise Apr 17 21:12:31 volumio volumio[20273]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Apr 17 21:12:31 volumio volumio[20273]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Apr 17 21:12:31 volumio volumio[20273]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 17 21:12:31 volumio sudo[20452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Apr 17 21:12:31 volumio sudo[20452]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio volumio[20273]: info: Volumio Calling Home Apr 17 21:12:31 volumio sudo[20452]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio sudo[20454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Apr 17 21:12:31 volumio sudo[20454]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio volumio[20273]: info: Preparing to generate the ALSA configuration file Apr 17 21:12:31 volumio volumio[20273]: info: Discovery: adding 9fb13099-8ee2-4aaf-bb9f-85978574bb86 Apr 17 21:12:31 volumio volumio[20273]: info: Discovery: Found device Volumio Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:31 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:31 volumio volumio[20273]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 21:12:31 volumio volumio[20273]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 17 21:12:31 volumio volumio[20273]: info: Reading ALSA contributions from plugins. Apr 17 21:12:31 volumio volumio[20273]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Apr 17 21:12:31 volumio volumio[20273]: info: MPD Permissions set Apr 17 21:12:31 volumio volumio[20273]: info: MPD Permissions set Apr 17 21:12:31 volumio volumio[20273]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Apr 17 21:12:31 volumio volumio[20273]: info: Spotify config file written Apr 17 21:12:31 volumio volumio[20273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio sudo[20461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 17 21:12:31 volumio sudo[20461]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio systemd[1]: Stopping go-librespot Daemon... Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: No need to fix Spotify hosts Apr 17 21:12:31 volumio systemd[1]: Started go-librespot Daemon. Apr 17 21:12:31 volumio go-librespot[20467]: go-librespot daemon starting... Apr 17 21:12:31 volumio sudo[20461]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio volumio[20273]: info: Volumio called home Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=info msg="running go-librespot 0.2.0" Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=debug msg="app state loaded" Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 17 21:12:31 volumio volumio[20273]: info: Starting Shairport Sync Apr 17 21:12:31 volumio volumio[20273]: info: Starting Shairport Sync Apr 17 21:12:31 volumio volumio[20273]: info: Starting Shairport Sync Apr 17 21:12:31 volumio sudo[20480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 21:12:31 volumio sudo[20480]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio sudo[20482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 21:12:31 volumio sudo[20482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::volumioGetState Apr 17 21:12:31 volumio volumio[20273]: info: CorePlayQueue::getTrack 0 Apr 17 21:12:31 volumio sudo[20486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 21:12:31 volumio sudo[20486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 21:12:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:31 volumio volumio[20273]: info: Asound.conf file unchanged, so no further update is needed Apr 17 21:12:31 volumio volumio[20273]: info: Output device has changed, restarting MPD Apr 17 21:12:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:31 volumio sudo[20480]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio sudo[20482]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 21:12:31 volumio volumio[20273]: info: Output device has changed, restarting Shairport Sync Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:31 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:31 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 21:12:31 volumio sudo[20493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 21:12:31 volumio sudo[20493]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:31 volumio sudo[20486]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 17 21:12:31 volumio go-librespot[20467]: time="2025-04-17T21:12:31+02:00" level=info msg="zeroconf server listening on port 36819" Apr 17 21:12:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:31 volumio sudo[20493]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:31 volumio sudo[20495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 21:12:31 volumio sudo[20495]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:31 volumio volumio[20273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 21:12:31 volumio volumio[20273]: info: Preparing to generate the ALSA configuration file Apr 17 21:12:31 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 17 21:12:31 volumio systemd[1]: mpd.service: Succeeded. Apr 17 21:12:31 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 21:12:31 volumio volumio[20273]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 21:12:31 volumio volumio[20273]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 17 21:12:31 volumio volumio[20273]: info: Reading ALSA contributions from plugins. Apr 17 21:12:31 volumio systemd[1]: Starting Music Player Daemon... Apr 17 21:12:32 volumio volumio[20273]: info: MPD Permissions set Apr 17 21:12:32 volumio volumio[20273]: info: Shairport-Sync Started Apr 17 21:12:32 volumio volumio[20273]: Error adding Membership: Error: addMembership EINVAL Apr 17 21:12:32 volumio volumio[20273]: info: Shairport-Sync Started Apr 17 21:12:32 volumio volumio[20273]: info: Shairport-Sync Started Apr 17 21:12:32 volumio volumio[20273]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 17 21:12:32 volumio volumio[20273]: SPOTIFY: BQB_zLOTXpQyt00qutyfsxlSexLyh3uN_fp4gh1xLnTu9Rrb4xtEmfZBrGz3RWMblW3LSFl_V0ezwskVQbzZsIdMVrHZuSYMOmvY7N_ETGSe9ZtiK5rHU4TdrW0jkdkWl9zl_p2xcZA_at3DnkQbH5I-qYIoFOERcrIPBgnsNhEPsZ6dko6WeNfdInA1URuf0_2pZ6HmPWSgfAwDm6JaesPfPkgqOEdQ2-zWR7cyBa-1Bmug_lFRpAdPBoJRVqjhPtsv2WS6dyAZA9TAwdw55zDQDIuUmx1KYQye5g Apr 17 21:12:32 volumio volumio[20273]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Apr 17 21:12:32 volumio volumio[20273]: info: New Spotify access token = BQB_zLOTXpQyt00qutyfsxlSexLyh3uN_fp4gh1xLnTu9Rrb4xtEmfZBrGz3RWMblW3LSFl_V0ezwskVQbzZsIdMVrHZuSYMOmvY7N_ETGSe9ZtiK5rHU4TdrW0jkdkWl9zl_p2xcZA_at3DnkQbH5I-qYIoFOERcrIPBgnsNhEPsZ6dko6WeNfdInA1URuf0_2pZ6HmPWSgfAwDm6JaesPfPkgqOEdQ2-zWR7cyBa-1Bmug_lFRpAdPBoJRVqjhPtsv2WS6dyAZA9TAwdw55zDQDIuUmx1KYQye5g Apr 17 21:12:32 volumio volumio[20273]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="obtained new client token: AADYnZkzoJXISj5J11Yrk0ts7lHLyDf+mx1wO1HiJZ1MvF+KMYuvzas1MjHYZo2ScO92Qg+xrrSXCJGx49VPWVtoo2Vn9cjPJPsg50yF/STyPcsTKAmJT8Dg0Tpcwlxh2YE0j2fxpfAxU62OfXxnMJP4H0SM14wOKKzfSAGIow6Rhk6TW+CCv+wNIa0aFXZ98R0AC9zYLl+BqK8P0cRQJY2QHDsFGVGUqz6Ef0LAqRygwE8ryEXJ+sNCxQ==" Apr 17 21:12:32 volumio volumio[20273]: (node:20273) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Apr 17 21:12:32 volumio volumio[20273]: (Use `node --trace-deprecation ...` to show where the warning was created) Apr 17 21:12:32 volumio volumio[20273]: (node:20273) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: Starting Shairport Sync Apr 17 21:12:32 volumio sudo[20504]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 21:12:32 volumio sudo[20504]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:32 volumio sudo[20504]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:32 volumio volumio[20273]: info: Asound.conf file unchanged, so no further update is needed Apr 17 21:12:32 volumio volumio[20273]: info: Output device has changed, restarting MPD Apr 17 21:12:32 volumio sudo[20512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 21:12:32 volumio sudo[20512]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 17 21:12:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 21:12:32 volumio volumio[20273]: info: Output device has changed, restarting Shairport Sync Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:32 volumio sudo[20515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 21:12:32 volumio sudo[20515]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:32 volumio sudo[20515]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:32 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 21:12:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:32 volumio sudo[20517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 21:12:32 volumio sudo[20512]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="completed keyexchange" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="completed challenge" Apr 17 21:12:32 volumio sudo[20517]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:32 volumio volumio[20273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 21:12:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 17 21:12:32 volumio systemd[1]: mpd.service: Succeeded. Apr 17 21:12:32 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 21:12:32 volumio systemd[1]: Starting Music Player Daemon... Apr 17 21:12:32 volumio volumio[20273]: info: MPD Permissions set Apr 17 21:12:32 volumio volumio[20273]: info: Shairport-Sync Started Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=info msg="authenticated AP as michele.coppo" Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:32 volumio volumio[20273]: info: Starting Shairport Sync Apr 17 21:12:32 volumio sudo[20533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 21:12:32 volumio sudo[20533]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:32 volumio sudo[20526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 21:12:32 volumio sudo[20526]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:32 volumio sudo[20526]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:32 volumio volumio[20273]: SPOTIFY: User informations: {"country":"IT","display_name":"michele.coppo","email":"michele.coppo@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/michele.coppo"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/michele.coppo","id":"michele.coppo","images":[],"product":"premium","type":"user","uri":"spotify:user:michele.coppo"} Apr 17 21:12:32 volumio volumio[20273]: info: Spotify Successfully logged in Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 21:12:32 volumio volumio[20273]: info: [1744917152316] CoreMusicLibrary::Adding element Spotify Apr 17 21:12:32 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 21:12:32 volumio volumio[20273]: Cannot find translation for source Personal Radio Apr 17 21:12:32 volumio volumio[20273]: Cannot find translation for source Podcast Apr 17 21:12:32 volumio volumio[20273]: Cannot find translation for source RADIO 357 Apr 17 21:12:32 volumio volumio[20273]: Cannot find translation for source Radio Paradise Apr 17 21:12:32 volumio volumio[20273]: Cannot find translation for source Spotify Apr 17 21:12:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 21:12:32 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 21:12:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 21:12:32 volumio sudo[20533]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:32 volumio volumio[20273]: info: Shairport-Sync Started Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=info msg="authenticated Login5 as michele.coppo" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="initializing zeroconf session, username: michele.coppo" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="dealer connection opened" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=trace msg="starting accesspoint recv loop" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=trace msg="starting dealer recv loop" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=trace msg="received accesspoint ping" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="received connection id: ZDNhYzg2ZTktMmRhOS00YTVmLTg2ZGUtYzE2NTA3ZmFhMWY1K2RlYWxlcit0Y3A6Ly8wYWNhNTgxNi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMzBBNzFDQ0ZCRTA2OUQ4OTVDQThBN0QxNThDRDAwRTk3QUE3QjYyMDU2N0QyNDk5MkZBQjI2NzEwNDZCQzMzNw==" Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=trace msg="received accesspoint pong ack" Apr 17 21:12:32 volumio volumio[20273]: info: Cannot mount NAS nas1 at system boot, trial number 2 ,retrying in 5 seconds Apr 17 21:12:32 volumio volumio[20273]: info: Cannot mount NAS nas2 at system boot, trial number 2 ,retrying in 5 seconds Apr 17 21:12:32 volumio go-librespot[20467]: time="2025-04-17T21:12:32+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 17 21:12:33 volumio mpd[20536]: Apr 17 21:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 21:12:33 volumio systemd[1]: Started Music Player Daemon. Apr 17 21:12:33 volumio sudo[20426]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:33 volumio sudo[20517]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:33 volumio sudo[20495]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:33 volumio sudo[20417]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:33 volumio volumio[20273]: info: Completed starting Core Plugins Apr 17 21:12:33 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:33 volumio volumio[20273]: info: ----- MyVolumio plugins startup ---- Apr 17 21:12:33 volumio volumio[20273]: info: ------------------------------------------- Apr 17 21:12:33 volumio volumio[20273]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 17 21:12:33 volumio systemd[1]: Started MPD OLED Plugin. Apr 17 21:12:33 volumio sudo[20454]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:33 volumio volumio[20273]: error: MPD error: The expression evaluated to a falsy value: Apr 17 21:12:33 volumio volumio[20273]: assert.ok(self.idling) Apr 17 21:12:33 volumio volumio[20273]: error: The expression evaluated to a falsy value: Apr 17 21:12:33 volumio volumio[20273]: assert.ok(self.idling) Apr 17 21:12:33 volumio volumio[20273]: error: MPD error: The expression evaluated to a falsy value: Apr 17 21:12:33 volumio volumio[20273]: assert.ok(self.idling) Apr 17 21:12:33 volumio volumio[20273]: error: The expression evaluated to a falsy value: Apr 17 21:12:33 volumio volumio[20273]: assert.ok(self.idling) Apr 17 21:12:33 volumio volumio[20273]: error: MPD error: The expression evaluated to a falsy value: Apr 17 21:12:33 volumio volumio[20273]: assert.ok(self.idling) Apr 17 21:12:33 volumio volumio[20273]: error: The expression evaluated to a falsy value: Apr 17 21:12:33 volumio volumio[20273]: assert.ok(self.idling) Apr 17 21:12:33 volumio volumio[20273]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Apr 17 21:12:33 volumio volumio[20273]: info: MPD running with PID20536 Apr 17 21:12:33 volumio volumio[20273]: ,establishing connection Apr 17 21:12:33 volumio volumio[20273]: error: updateQueue error: null Apr 17 21:12:33 volumio volumio[20273]: error: updateQueue error: null Apr 17 21:12:33 volumio volumio[20273]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 17 21:12:33 volumio volumio[20273]: 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 Apr 17 21:12:33 volumio volumio[20273]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 17 21:12:33 volumio volumio[20273]: info: camilladsp service started and running in background, instance 1 Apr 17 21:12:33 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 21:12:33 volumio volumio[20273]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 21:12:33 volumio volumio[20273]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 17 21:12:33 volumio volumio[20273]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 21:12:33 volumio volumio[20273]: info: FusionDsp loaded Apr 17 21:12:33 volumio volumio[20273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 21:12:33 volumio sudo[20556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 17 21:12:33 volumio sudo[20556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 21:12:33 volumio volumio[20273]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 17 21:12:33 volumio volumio[20273]: info: Adding Signal Path Element [object Object] Apr 17 21:12:33 volumio volumio[20273]: info: Adding fusiondspeq DSP Signal Path Element Apr 17 21:12:33 volumio volumio[20273]: info: FusionDsp - ---- installed callbackRead Apr 17 21:12:33 volumio volumio[20273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 21:12:33 volumio volumio[20273]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 17 21:12:33 volumio volumio[20273]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 17 21:12:33 volumio volumio[20273]: at onErrorNT (internal/child_process.js:465:16) Apr 17 21:12:33 volumio volumio[20273]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 17 21:12:33 volumio volumio[20273]: errno: -2, Apr 17 21:12:33 volumio volumio[20273]: code: 'ENOENT', Apr 17 21:12:33 volumio volumio[20273]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 21:12:33 volumio volumio[20273]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 21:12:33 volumio volumio[20273]: spawnargs: [ Apr 17 21:12:33 volumio volumio[20273]: '-p', Apr 17 21:12:33 volumio volumio[20273]: 9876, Apr 17 21:12:33 volumio volumio[20273]: '-o', Apr 17 21:12:33 volumio volumio[20273]: '/tmp/camilladsp.log', Apr 17 21:12:33 volumio volumio[20273]: '-l', Apr 17 21:12:33 volumio volumio[20273]: 'warn', Apr 17 21:12:33 volumio volumio[20273]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 17 21:12:33 volumio volumio[20273]: ] Apr 17 21:12:33 volumio volumio[20273]: } Apr 17 21:12:33 volumio volumio[20273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 21:12:33 volumio systemd[1]: Started FusionDsp Daemon. Apr 17 21:12:33 volumio sudo[20556]: pam_unix(sudo:session): session closed for user root Apr 17 21:12:33 volumio volumio[20558]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 17 21:12:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 17 21:12:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 17 21:12:33 volumio sudo[20568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-17 21:11 Apr 17 21:12:33 volumio sudo[20568]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"