-- Logs begin at Tue 2025-11-04 11:46:00 CET, end at Tue 2025-11-04 13:53:39 CET. -- Nov 04 13:52:31 volumio volumio[1143]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf Nov 04 13:52:33 volumio volumio[1143]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf Nov 04 13:52:33 volumio volumio[1143]: info: Folder /tmp/plugins removed Nov 04 13:52:34 volumio volumio[1143]: info: Check plugin dependencies Nov 04 13:52:34 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 13:52:34 volumio volumio[1143]: info: Checking if plugin already exists Nov 04 13:52:34 volumio volumio[1143]: info: Rename folder Nov 04 13:52:34 volumio volumio[1143]: info: Folder /tmp/downloaded_plugin.zip removed Nov 04 13:52:34 volumio volumio[1143]: info: Move to category Nov 04 13:52:35 volumio nmbd[970]: [2025/11/04 13:52:35.556529, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 04 13:52:35 volumio nmbd[970]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.13 for name WORKGROUP<1d>. Nov 04 13:52:35 volumio nmbd[970]: This response was from IP 192.168.10.57, reporting an IP address of 192.168.10.57. Nov 04 13:52:47 volumio volumio-remote-updater[803]: [2025-11-04 13:52:47] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Nov 04 13:52:47 volumio wpa_supplicant[1070]: wlan0: WPA: Group rekeying completed with 2c:c8:1b:34:10:a9 [GTK=CCMP] Nov 04 13:52:52 volumio volumio-remote-updater[803]: [2025-11-04 13:52:52] [connect] Successful connection Nov 04 13:53:01 volumio volumio[1143]: info: Checking if install.sh is present Nov 04 13:53:01 volumio volumio[1143]: info: Executing install.sh Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: verbose: New Socket.io Connection to volumio.lan from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 04 13:53:01 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.10.13 from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 04 13:53:01 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.10.13 from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 04 13:53:01 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.10.13 from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 04 13:53:01 volumio sudo[17164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Nov 04 13:53:01 volumio sudo[17164]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:01 volumio volumio[1143]: info: Installing Go-librespot Nov 04 13:53:01 volumio volumio[1143]: info: Checking old vollibrespot installs Nov 04 13:53:01 volumio systemd[1]: Reloading. Nov 04 13:53:01 volumio volumio[1143]: verbose: New Socket.io Connection to volumio.lan from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 04 13:53:01 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:01 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:01 volumio volumio[1143]: info: CoreCommandRouter::volumioGetQueue Nov 04 13:53:01 volumio volumio[1143]: info: CoreStateMachine::getQueue Nov 04 13:53:01 volumio volumio[1143]: info: CorePlayQueue::getQueue Nov 04 13:53:01 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 04 13:53:01 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Nov 04 13:53:01 volumio volumio[1143]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Nov 04 13:53:01 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Nov 04 13:53:01 volumio volumio[1143]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Nov 04 13:53:01 volumio volumio[1143]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Nov 04 13:53:01 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Nov 04 13:53:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 04 13:53:02 volumio volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:02 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:02 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 04 13:53:02 volumio systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 04 13:53:02 volumio volumio[1143]: info: Downloading daemon Nov 04 13:53:02 volumio volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 04 13:53:02 volumio volumio[1143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 04 13:53:02 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.10.13 from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:02 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:02 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 13:53:02 volumio volumio[1143]: info: Received Get System Info Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 13:53:02 volumio volumio[1143]: info: Discovery: Getting this device information Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:02 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 13:53:02 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:02 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:02 volumio volumio[1143]: info: Listing playlists Nov 04 13:53:03 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 04 13:53:03 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:03 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:03 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:03 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:03 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:03 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:03 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:04 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:04 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:04 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:04 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:04 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:05 volumio volumio-remote-updater[803]: [2025-11-04 13:53:05] [connect] Successful connection Nov 04 13:53:05 volumio volumio-remote-updater[803]: [2025-11-04 13:53:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762260785 101 Nov 04 13:53:05 volumio volumio[1143]: 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: 10 Nov 04 13:53:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetQueue Nov 04 13:53:10 volumio volumio[1143]: info: CoreStateMachine::getQueue Nov 04 13:53:10 volumio volumio[1143]: info: CorePlayQueue::getQueue Nov 04 13:53:11 volumio volumio[1143]: info: Creating directories Nov 04 13:53:11 volumio volumio[1143]: info: Creating data directory Nov 04 13:53:11 volumio volumio[1143]: info: Creating Start Script Nov 04 13:53:11 volumio systemd[1]: Reloading. Nov 04 13:53:11 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 04 13:53:11 volumio sudo[17164]: pam_unix(sudo:session): session closed for user root Nov 04 13:53:11 volumio volumio[1143]: Plugin install end detected on script Nov 04 13:53:11 volumio volumio[1143]: info: Install script completed Nov 04 13:53:11 volumio volumio[1143]: info: Adding reference to registry Nov 04 13:53:11 volumio volumio[1143]: info: Done installing plugin. Nov 04 13:53:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 13:53:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 13:53:12 volumio volumio[1143]: info: Folder /tmp/plugins removed Nov 04 13:53:12 volumio volumio[1143]: info: Folder /tmp/downloaded_plugin.zip removed Nov 04 13:53:12 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:12 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:12 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:12 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:12 volumio volumio[1143]: info: Folder /data/temp removed Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:12 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:13 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:14 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:14 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:17 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:17 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:17 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:18 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:18 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:18 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:18 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:19 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:19 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:19 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:21 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.10.13 from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Nov 04 13:53:26 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.10.13 from 192.168.10.206 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:26 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:26 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 13:53:26 volumio volumio[1143]: info: Received Get System Info Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 13:53:26 volumio volumio[1143]: info: Discovery: Getting this device information Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:26 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Nov 04 13:53:26 volumio volumio[1143]: info: CorePlayQueue::getTrack 9 Nov 04 13:53:26 volumio volumio[1143]: info: Listing playlists Nov 04 13:53:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetQueue Nov 04 13:53:26 volumio volumio[1143]: info: CoreStateMachine::getQueue Nov 04 13:53:26 volumio volumio[1143]: info: CorePlayQueue::getQueue Nov 04 13:53:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 04 13:53:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:27 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:27 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:27 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:28 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:29 volumio volumio[1143]: info: Enabling plugin spop Nov 04 13:53:29 volumio volumio[1143]: info: Loading plugin "spop"... Nov 04 13:53:30 volumio volumio[1143]: info: PLUGIN START: spop Nov 04 13:53:30 volumio volumio[1143]: info: Creating Spotify config file Nov 04 13:53:30 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:30 volumio volumio[1143]: info: Done. Nov 04 13:53:30 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:30 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:30 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:30 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:30 volumio volumio[1143]: info: Spotify config file written Nov 04 13:53:30 volumio volumio[1143]: info: No need to fix Spotify hosts Nov 04 13:53:30 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:30 volumio sudo[17314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 04 13:53:30 volumio sudo[17314]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 13:53:30 volumio systemd[1]: Started go-librespot Daemon. Nov 04 13:53:30 volumio go-librespot[17316]: go-librespot daemon starting... Nov 04 13:53:30 volumio sudo[17314]: pam_unix(sudo:session): session closed for user root Nov 04 13:53:30 volumio go-librespot[17316]: time="2025-11-04T13:53:30+01:00" level=info msg="running go-librespot 0.4.0" Nov 04 13:53:30 volumio go-librespot[17316]: time="2025-11-04T13:53:30+01:00" level=debug msg="no app state found" Nov 04 13:53:30 volumio go-librespot[17316]: time="2025-11-04T13:53:30+01:00" level=debug msg="stored credentials not found" Nov 04 13:53:30 volumio go-librespot[17316]: time="2025-11-04T13:53:30+01:00" level=info msg="generated new device id: 9e318d33ee265e656f35c16047c5aa546fe3f915" Nov 04 13:53:30 volumio go-librespot[17316]: time="2025-11-04T13:53:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 13:53:30 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:31 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:31 volumio go-librespot[17316]: time="2025-11-04T13:53:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 13:53:31 volumio go-librespot[17316]: time="2025-11-04T13:53:31+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 13:53:31 volumio go-librespot[17316]: time="2025-11-04T13:53:31+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 13:53:31 volumio go-librespot[17316]: time="2025-11-04T13:53:31+01:00" level=info msg="zeroconf server listening on port 40949" Nov 04 13:53:31 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:31 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:31 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:31 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:31 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:34 volumio volumio[1143]: info: go-librespot daemon successfully initialized Nov 04 13:53:35 volumio volumio[1143]: info: Enabling plugin fusiondsp Nov 04 13:53:35 volumio volumio[1143]: info: Loading plugin "fusiondsp"... Nov 04 13:53:35 volumio volumio[1143]: info: Applying required configuration parameters for plugin fusiondsp Nov 04 13:53:35 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Nov 04 13:53:35 volumio volumio[1143]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 04 13:53:35 volumio volumio[1143]: info: Reading ALSA contributions from plugins. Nov 04 13:53:35 volumio volumio[1143]: info: Asound.conf file unchanged, so no further update is needed Nov 04 13:53:35 volumio volumio[1143]: info: Output device has changed, restarting MPD Nov 04 13:53:35 volumio sudo[17334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 04 13:53:35 volumio sudo[17334]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 13:53:35 volumio sudo[17334]: pam_unix(sudo:session): session closed for user root Nov 04 13:53:35 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Nov 04 13:53:35 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:35 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 13:53:35 volumio sudo[17337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 13:53:35 volumio sudo[17337]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 13:53:35 volumio systemd[1]: Stopping Music Player Daemon... Nov 04 13:53:35 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 04 13:53:35 volumio volumio[1143]: info: PLUGIN START: fusiondsp Nov 04 13:53:35 volumio volumio[1143]: info: Loading i18n strings for locale cs Nov 04 13:53:35 volumio volumio[1143]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Nov 04 13:53:35 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 04 13:53:35 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:35 volumio volumio[1143]: info: FusionDsp - mixtype--------------------- Hardware Nov 04 13:53:35 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Nov 04 13:53:36 volumio volumio[1143]: info: Done. Nov 04 13:53:36 volumio volumio[1143]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 04 13:53:36 volumio volumio[1143]: info: Reading ALSA contributions from plugins. Nov 04 13:53:36 volumio volumio[1143]: info: MPD Permissions set Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:36 volumio volumio[1143]: error: Upnp client error: Error: This socket has been ended by the other party Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio systemd[1]: mpd.service: Succeeded. Nov 04 13:53:36 volumio systemd[1]: Stopped Music Player Daemon. Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 04 13:53:36 volumio systemd[1]: Starting Music Player Daemon... Nov 04 13:53:36 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 13:53:36 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:36 volumio sudo[17346]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 13:53:36 volumio sudo[17346]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 13:53:36 volumio sudo[17346]: pam_unix(sudo:session): session closed for user root Nov 04 13:53:36 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:36 volumio volumio[1143]: info: Starting Shairport Sync Nov 04 13:53:36 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:36 volumio sudo[17353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 04 13:53:36 volumio sudo[17353]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 13:53:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 04 13:53:36 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 04 13:53:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 04 13:53:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 04 13:53:36 volumio sudo[17353]: pam_unix(sudo:session): session closed for user root Nov 04 13:53:36 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:37 volumio volumio[1143]: info: FusionDsp - no preset used Nov 04 13:53:37 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:37 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:37 volumio volumio[1143]: info: FusionDsp - undefined Nov 04 13:53:37 volumio volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 13:53:37 volumio volumio[1143]: TypeError: Cannot read property 'off' of undefined Nov 04 13:53:37 volumio volumio[1143]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Nov 04 13:53:37 volumio volumio[1143]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 04 13:53:37 volumio volumio[1143]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 04 13:53:37 volumio volumio[1143]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Nov 04 13:53:37 volumio volumio[1143]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Nov 04 13:53:37 volumio volumio[1143]: at Socket.emit (events.js:400:28) Nov 04 13:53:37 volumio volumio[1143]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 04 13:53:37 volumio volumio[1143]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 04 13:53:37 volumio volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 13:53:38 volumio mpd[17351]: Nov 04 13:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 13:53:38 volumio systemd[1]: Started Music Player Daemon. Nov 04 13:53:38 volumio sudo[17337]: pam_unix(sudo:session): session closed for user root Nov 04 13:53:39 volumio sudo[17384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 13:52 Nov 04 13:53:39 volumio sudo[17384]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 29 Jul 2025 12:40:52 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="053d26d2b7f23be800db334519309821"