-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Sat 2026-04-11 18:54:46 EEST. -- Apr 11 18:53:00 volumio dhcpcd[1142]: wlan0: offered 192.168.0.222 from 192.168.0.1 Apr 11 18:53:00 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:00 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:00 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:00 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:00 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:00 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:00 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:00 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:00 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:00 volumio dhcpcd[1142]: wlan0: probing address 192.168.0.222/24 Apr 11 18:53:00 volumio ntpd[978]: Deleting interface #4 wlan0, 192.168.0.222#123, interface stats: received=0, sent=0, dropped=0, active_time=17 secs Apr 11 18:53:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Apr 11 18:53:01 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:01 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:01 volumio volumio[1726]: Traceback (most recent call last): Apr 11 18:53:01 volumio volumio[1726]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:01 volumio volumio[1726]: from aiohttp import web Apr 11 18:53:01 volumio volumio[1726]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:02 volumio volumio[1232]: info: MYVOLUMIO: Adding device Apr 11 18:53:02 volumio volumio[1232]: info: MYVOLUMIO: Evaluating Server Apr 11 18:53:03 volumio volumio[1232]: info: Setting Geolocation for MyVolumio to eu11 Apr 11 18:53:03 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:03 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:03 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Apr 11 18:53:03 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:03 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:03 volumio volumio[1745]: Traceback (most recent call last): Apr 11 18:53:03 volumio volumio[1745]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:03 volumio volumio[1745]: from aiohttp import web Apr 11 18:53:03 volumio volumio[1745]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:03 volumio volumio[1232]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 11 18:53:04 volumio volumio[1232]: info: Volumio Network Manager: Network status updated: 1 Apr 11 18:53:04 volumio volumio[1232]: info: Updating MyVolumio device info Apr 11 18:53:04 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:04 volumio volumio[1232]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 11 18:53:04 volumio volumio[1630]: .....................................................................................................................................................................................................................++++ Apr 11 18:53:05 volumio volumio[1630]: ...............++++ Apr 11 18:53:05 volumio volumio[1630]: e is 65537 (0x010001) Apr 11 18:53:05 volumio volumio[1630]: writing RSA key Apr 11 18:53:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Apr 11 18:53:05 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:05 volumio dhcpcd[1142]: wlan0: leased 192.168.0.222 for 7200 seconds Apr 11 18:53:05 volumio avahi-daemon[753]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.222. Apr 11 18:53:05 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:05 volumio avahi-daemon[753]: New relevant interface wlan0.IPv4 for mDNS. Apr 11 18:53:05 volumio avahi-daemon[753]: Registering new address record for 192.168.0.222 on wlan0.IPv4. Apr 11 18:53:05 volumio dhcpcd[1142]: wlan0: adding route to 192.168.0.0/24 Apr 11 18:53:05 volumio dhcpcd[1142]: wlan0: adding default route via 192.168.0.1 Apr 11 18:53:05 volumio volumio[1767]: Traceback (most recent call last): Apr 11 18:53:05 volumio volumio[1767]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:05 volumio volumio[1767]: from aiohttp import web Apr 11 18:53:05 volumio volumio[1767]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:06 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:06 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:06 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:06 volumio volumio[1232]: info: Discovery: adding 374dd8f3-fe36-42f9-84b4-17c8d7c5878a Apr 11 18:53:06 volumio volumio[1232]: info: Discovery: Found device Volumio Apr 11 18:53:06 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:06 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:06 volumio ntpd[978]: Listen normally on 5 wlan0 192.168.0.222:123 Apr 11 18:53:06 volumio ntpd[978]: new interface(s) found: waking up resolver Apr 11 18:53:07 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Apr 11 18:53:07 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:07 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:07 volumio volumio[1803]: Traceback (most recent call last): Apr 11 18:53:07 volumio volumio[1803]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:07 volumio volumio[1803]: from aiohttp import web Apr 11 18:53:07 volumio volumio[1803]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:07 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:07 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:10 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 18:53:10 volumio volumio[1232]: 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 11 18:53:10 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:10 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Apr 11 18:53:10 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:10 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:10 volumio volumio[1809]: Traceback (most recent call last): Apr 11 18:53:10 volumio volumio[1809]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:10 volumio volumio[1809]: from aiohttp import web Apr 11 18:53:10 volumio volumio[1809]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 11. Apr 11 18:53:12 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:12 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:12 volumio volumio[1810]: Traceback (most recent call last): Apr 11 18:53:12 volumio volumio[1810]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:12 volumio volumio[1810]: from aiohttp import web Apr 11 18:53:12 volumio volumio[1810]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:13 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 11 18:53:14 volumio volumio[1232]: info: Volumio Network Manager: Network status updated: 3 Apr 11 18:53:14 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 12. Apr 11 18:53:14 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:14 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:14 volumio volumio[1827]: Traceback (most recent call last): Apr 11 18:53:14 volumio volumio[1827]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:14 volumio volumio[1827]: from aiohttp import web Apr 11 18:53:14 volumio volumio[1827]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 18:53:15 volumio volumio[1232]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 11 18:53:15 volumio volumio[1232]: info: Completed starting MyVolumio Plugin Apr 11 18:53:15 volumio volumio[1232]: [Metrics] CommandRouter: 31s 725.39ms Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::volumiosetStartupVolume Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::Close All Modals sent Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::Close All Modals sent Apr 11 18:53:15 volumio volumio[1232]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 11 18:53:15 volumio volumio[1232]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 11 18:53:15 volumio volumio[1232]: info: camilladsp stopping service pid 1588... Apr 11 18:53:15 volumio volumio[1232]: info: camilladsp service terminated, instance 1 Apr 11 18:53:15 volumio volumio[1232]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 11 18:53:15 volumio volumio[1232]: info: camilladsp service started and running in background, instance 1 Apr 11 18:53:15 volumio volumio[1232]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 11 18:53:15 volumio volumio[1232]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 11 18:53:15 volumio volumio[1232]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 11 18:53:15 volumio volumio[1232]: error: FusionDsp - WebSocket error: [object Object] Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 18:53:15 volumio volumio-remote-updater[755]: No test mode Apr 11 18:53:15 volumio volumio-remote-updater[755]: No alpha test mode Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 11 18:53:15 volumio volumio5-onboarding[1493]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 11 18:53:15 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:15 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 11 18:53:15 volumio volumio[1232]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 11 18:53:15 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 11 18:53:16 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 18:53:16 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 11 18:53:16 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Apr 11 18:53:16 volumio systemd[1]: Started Volumio5 Onboarding Server. Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.068+03:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Apr 11 18:53:16 volumio volumio[1232]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 11 18:53:16 volumio volumio[1232]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 11 18:53:16 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:16 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:16 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.080+03:00 level=INFO msg="system info for 1cefc987691025defcd7de2978ae6c7e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Apr 11 18:53:16 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:16 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:16 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.085+03:00 level=INFO msg="enabling local network discovery" Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.090+03:00 level=INFO msg="enabling BLE discovery" Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.127+03:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 11 18:53:16 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:16 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:16 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:16 volumio volumio-remote-updater[755]: No test mode Apr 11 18:53:16 volumio volumio-remote-updater[755]: No alpha test mode Apr 11 18:53:16 volumio volumio[1232]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 11 18:53:16 volumio go-librespot[1526]: time="2026-04-11T18:53:16+03:00" level=trace msg="sent dealer ping" Apr 11 18:53:16 volumio go-librespot[1526]: time="2026-04-11T18:53:16+03:00" level=trace msg="received dealer pong" Apr 11 18:53:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 13. Apr 11 18:53:16 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:16 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.826+03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Apr 11 18:53:16 volumio volumio[1856]: Traceback (most recent call last): Apr 11 18:53:16 volumio volumio[1856]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:16 volumio volumio[1856]: from aiohttp import web Apr 11 18:53:16 volumio volumio[1856]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.826+03:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Apr 11 18:53:16 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:16.826+03:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:16 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 18:53:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:18 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:18 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 18:53:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 14. Apr 11 18:53:19 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:19 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:19 volumio volumio[1858]: Traceback (most recent call last): Apr 11 18:53:19 volumio volumio[1858]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:19 volumio volumio[1858]: from aiohttp import web Apr 11 18:53:19 volumio volumio[1858]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:19 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:19.891+03:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=fMfVxrDxvscW3fRPMLnWIzP658D3 tokenExpiry=2026-04-11T19:53:19.891+03:00 Apr 11 18:53:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 15. Apr 11 18:53:21 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:21 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:21 volumio volumio[1866]: Traceback (most recent call last): Apr 11 18:53:21 volumio volumio[1866]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:21 volumio volumio[1866]: from aiohttp import web Apr 11 18:53:21 volumio volumio[1866]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:21 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:21.594+03:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 11 18:53:22 volumio volumio[1232]: info: BOOT COMPLETED Apr 11 18:53:23 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 16. Apr 11 18:53:23 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:23 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:23 volumio volumio[1867]: Traceback (most recent call last): Apr 11 18:53:23 volumio volumio[1867]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:23 volumio volumio[1867]: from aiohttp import web Apr 11 18:53:23 volumio volumio[1867]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:23 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:23 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:25 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:25 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 17. Apr 11 18:53:25 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:25 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:25 volumio volumio[1882]: Traceback (most recent call last): Apr 11 18:53:25 volumio volumio[1882]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:25 volumio volumio[1882]: from aiohttp import web Apr 11 18:53:25 volumio volumio[1882]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:25 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:25 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:28 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 18. Apr 11 18:53:28 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:28 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:28 volumio volumio[1921]: Traceback (most recent call last): Apr 11 18:53:28 volumio volumio[1921]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:28 volumio volumio[1921]: from aiohttp import web Apr 11 18:53:28 volumio volumio[1921]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 19. Apr 11 18:53:30 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:30 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:30 volumio volumio[1922]: Traceback (most recent call last): Apr 11 18:53:30 volumio volumio[1922]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:30 volumio volumio[1922]: from aiohttp import web Apr 11 18:53:30 volumio volumio[1922]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:32 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 20. Apr 11 18:53:32 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:32 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:32 volumio volumio[1923]: Traceback (most recent call last): Apr 11 18:53:32 volumio volumio[1923]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:32 volumio volumio[1923]: from aiohttp import web Apr 11 18:53:32 volumio volumio[1923]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:32 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:32 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:34 volumio ntpd[978]: Soliciting pool server 217.156.67.138 Apr 11 18:53:34 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:34 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 21. Apr 11 18:53:34 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:34 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:34 volumio volumio[1938]: Traceback (most recent call last): Apr 11 18:53:34 volumio volumio[1938]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:34 volumio volumio[1938]: from aiohttp import web Apr 11 18:53:34 volumio volumio[1938]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:34 volumio ntpd[978]: Soliciting pool server 91.236.251.34 Apr 11 18:53:35 volumio ntpd[978]: Soliciting pool server 193.106.144.6 Apr 11 18:53:35 volumio ntpd[978]: Soliciting pool server 31.28.161.71 Apr 11 18:53:35 volumio ntpd[978]: Soliciting pool server 194.8.146.38 Apr 11 18:53:36 volumio ntpd[978]: Soliciting pool server 193.106.144.7 Apr 11 18:53:36 volumio ntpd[978]: Soliciting pool server 213.231.2.124 Apr 11 18:53:36 volumio ntpd[978]: Soliciting pool server 91.236.251.29 Apr 11 18:53:36 volumio ntpd[978]: Soliciting pool server 194.54.80.29 Apr 11 18:53:37 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:37 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 22. Apr 11 18:53:37 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:37 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:37 volumio volumio[1939]: Traceback (most recent call last): Apr 11 18:53:37 volumio volumio[1939]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:37 volumio volumio[1939]: from aiohttp import web Apr 11 18:53:37 volumio volumio[1939]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:37 volumio ntpd[978]: Soliciting pool server 162.159.200.123 Apr 11 18:53:37 volumio ntpd[978]: Soliciting pool server 162.159.200.1 Apr 11 18:53:37 volumio ntpd[978]: Soliciting pool server 94.158.46.150 Apr 11 18:53:37 volumio ntpd[978]: Soliciting pool server 31.133.97.13 Apr 11 18:53:38 volumio ntpd[978]: Soliciting pool server 91.236.251.31 Apr 11 18:53:38 volumio ntpd[978]: Soliciting pool server 79.142.192.4 Apr 11 18:53:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 23. Apr 11 18:53:39 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:39 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:39 volumio volumio[1940]: Traceback (most recent call last): Apr 11 18:53:39 volumio volumio[1940]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:39 volumio volumio[1940]: from aiohttp import web Apr 11 18:53:39 volumio volumio[1940]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:39 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:39 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:39 volumio ntpd[978]: Soliciting pool server 91.236.251.24 Apr 11 18:53:40 volumio ntpd[978]: Soliciting pool server 2001:67c:440:f620:91:236:251:34 Apr 11 18:53:41 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:41 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 24. Apr 11 18:53:41 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:41 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:41 volumio volumio[1941]: Traceback (most recent call last): Apr 11 18:53:41 volumio volumio[1941]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:41 volumio volumio[1941]: from aiohttp import web Apr 11 18:53:41 volumio volumio[1941]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:41 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:41 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:44 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:44 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 25. Apr 11 18:53:44 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:44 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:44 volumio volumio[1942]: Traceback (most recent call last): Apr 11 18:53:44 volumio volumio[1942]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:44 volumio volumio[1942]: from aiohttp import web Apr 11 18:53:44 volumio volumio[1942]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:44 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:44 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:46 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:46 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 26. Apr 11 18:53:46 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:46 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:46 volumio volumio[1957]: Traceback (most recent call last): Apr 11 18:53:46 volumio volumio[1957]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:46 volumio volumio[1957]: from aiohttp import web Apr 11 18:53:46 volumio volumio[1957]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:47 volumio go-librespot[1526]: time="2026-04-11T18:53:47+03:00" level=trace msg="sent dealer ping" Apr 11 18:53:47 volumio go-librespot[1526]: time="2026-04-11T18:53:47+03:00" level=trace msg="received dealer pong" Apr 11 18:53:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 27. Apr 11 18:53:48 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:48 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:48 volumio volumio[1958]: Traceback (most recent call last): Apr 11 18:53:48 volumio volumio[1958]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:48 volumio volumio[1958]: from aiohttp import web Apr 11 18:53:48 volumio volumio[1958]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.280+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.231:55402 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.280+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.231:55260 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.322+03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.231:55402 @ 0x2db48a0" latency=14.914959491s timeout=10s Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.322+03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.322+03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.231:55402 @ 0x2db48a0" latency=14.915929676s platform=PLATFORM_ANDROID version=5.260331.0 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:50 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:50 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:50 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.327+03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" name=Volumio Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.328+03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" language=uk Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.330+03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" timezone=Europe/Kiev Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.330+03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" available=true connected=true macAddress=2c:cf:67:54:ec:df ip4Address=192.168.0.221/24 ip6Address= Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.332+03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" available=true connected=true macAddress=2c:cf:67:54:ec:e0 ip4Address=192.168.0.222/24 ip6Address= ssid=TPL_8A18 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.332+03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" setupComplete=true Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 18:53:50 volumio volumio[1232]: amixer -c 1 info | grep "vc4-hdmi-0" Apr 11 18:53:50 volumio volumio[1232]: Card hw:1 'vc4hdmi0'/'vc4-hdmi-0' Apr 11 18:53:50 volumio volumio[1232]: amixer -c 2 info | grep "vc4-hdmi-1" Apr 11 18:53:50 volumio volumio[1232]: Card hw:2 'vc4hdmi1'/'vc4-hdmi-1' Apr 11 18:53:50 volumio volumio[1232]: amixer -c 0 info | grep "RPi DAC+" Apr 11 18:53:50 volumio volumio[1232]: Card hw:0 'DAC'/'RPi DAC+' Apr 11 18:53:50 volumio volumio[1232]: amixer -c 0 info | grep "Raspberry Pi DAC+" Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.468+03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" selectedOutputId=0 Apr 11 18:53:50 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:50 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:50 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.497+03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" currentVersion=3.912 latestVersion=3.912 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.498+03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" status=UPDATE_STATUS_NONE progress=0 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.498+03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" userId=fMfVxrDxvscW3fRPMLnWIzP658D3 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.498+03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" providers=9 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.498+03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" plugins=54 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:50 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.500+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" state=STATUS_STOPPED positionMs=0 volume=33 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.501+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55402 @ 0x2db48a0" id="mnt/INTERNAL/Nirvana - Nevermind - (1991)/01 - Smells Like Teen Spirit.flac" title="Smells Like Teen Spirit" Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.635+03:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.231:55402 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.635+03:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.231:55402 Apr 11 18:53:50 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:50.648+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.231:55444 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:50 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:50 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:50 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.222:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:50 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:51 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:51 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 28. Apr 11 18:53:51 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:51 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:51 volumio volumio[1983]: Traceback (most recent call last): Apr 11 18:53:51 volumio volumio[1983]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:51 volumio volumio[1983]: from aiohttp import web Apr 11 18:53:51 volumio volumio[1983]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:51 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:51 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:51 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:51 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:51 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.221:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:51 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:51 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:51 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.222:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:51 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:52 volumio volumio5-onboarding[1843]: time=2026-04-11T18:53:52.409+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.231:55444 @ 0x2cd8e70" latency=14.917766037s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:52 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:52 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:52 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.221:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:52 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:52 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:52 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.222:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:53:52 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:53:53 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 18:53:53 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 18:53:53 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Apr 11 18:53:53 volumio sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 18:53:53 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 18:53:53 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root Apr 11 18:53:53 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.221 from 192.168.0.231 UA: Mozilla/5.0 (Linux; Android 15; moto g54 5G Build/V1TDS35H.83-20-5-8-2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 11 18:53:53 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:53 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 29. Apr 11 18:53:53 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:53 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:53 volumio volumio[1990]: Traceback (most recent call last): Apr 11 18:53:53 volumio volumio[1990]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:53 volumio volumio[1990]: from aiohttp import web Apr 11 18:53:53 volumio volumio[1990]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:53 volumio sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 18:53:53 volumio sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 18:53:53 volumio sudo[1992]: pam_unix(sudo:session): session closed for user root Apr 11 18:53:53 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 18:53:53 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 18:53:53 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Apr 11 18:53:53 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.221 from 192.168.0.231 UA: Mozilla/5.0 (Linux; Android 15; moto g54 5G Build/V1TDS35H.83-20-5-8-2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:53 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 18:53:53 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:53 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:53 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:53 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:53 volumio volumio[1232]: info: Listing playlists Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 18:53:53 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 18:53:54 volumio volumio[1232]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 18:53:54 volumio volumio[1232]: info: FusionDsp - Apr 11 18:53:54 volumio volumio[1232]: info: FusionDsp - undefined Apr 11 18:53:54 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:55 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:55 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:55 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 11 18:53:55 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:55 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 30. Apr 11 18:53:55 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:55 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:55 volumio volumio[2011]: Traceback (most recent call last): Apr 11 18:53:55 volumio volumio[2011]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:55 volumio volumio[2011]: from aiohttp import web Apr 11 18:53:55 volumio volumio[2011]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:57 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:57 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:53:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 31. Apr 11 18:53:57 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:53:57 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:57 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:57 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:57 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:57 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:57 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:57 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:53:57 volumio volumio[2012]: Traceback (most recent call last): Apr 11 18:53:57 volumio volumio[2012]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:53:57 volumio volumio[2012]: from aiohttp import web Apr 11 18:53:57 volumio volumio[2012]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:53:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:53:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:53:58 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:58 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:58 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:58 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:58 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:58 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:58 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:58 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:53:59 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 18:53:59 volumio volumio[1232]: info: Received Get System Info Apr 11 18:53:59 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:53:59 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:53:59 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:53:59 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:53:59 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:53:59 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:54:00 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 32. Apr 11 18:54:00 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:00 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:00 volumio volumio[2013]: Traceback (most recent call last): Apr 11 18:54:00 volumio volumio[2013]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:00 volumio volumio[2013]: from aiohttp import web Apr 11 18:54:00 volumio volumio[2013]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:00 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:00 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:02 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 33. Apr 11 18:54:02 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:02 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:02 volumio volumio[2014]: Traceback (most recent call last): Apr 11 18:54:02 volumio volumio[2014]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:02 volumio volumio[2014]: from aiohttp import web Apr 11 18:54:02 volumio volumio[2014]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:03 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 11 18:54:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 34. Apr 11 18:54:04 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:04 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:04 volumio volumio[2015]: Traceback (most recent call last): Apr 11 18:54:04 volumio volumio[2015]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:04 volumio volumio[2015]: from aiohttp import web Apr 11 18:54:04 volumio volumio[2015]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:04 volumio volumio[1232]: info: CoreCommandRouter::volumioGetQueue Apr 11 18:54:04 volumio volumio[1232]: info: CoreStateMachine::getQueue Apr 11 18:54:04 volumio volumio[1232]: info: CorePlayQueue::getQueue Apr 11 18:54:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 35. Apr 11 18:54:06 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:06 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:06 volumio volumio[2030]: Traceback (most recent call last): Apr 11 18:54:06 volumio volumio[2030]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:06 volumio volumio[2030]: from aiohttp import web Apr 11 18:54:06 volumio volumio[2030]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:09 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 36. Apr 11 18:54:09 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:09 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:09 volumio volumio[2031]: Traceback (most recent call last): Apr 11 18:54:09 volumio volumio[2031]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:09 volumio volumio[2031]: from aiohttp import web Apr 11 18:54:09 volumio volumio[2031]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:09 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:09 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:11 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 37. Apr 11 18:54:11 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:11 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:11 volumio volumio[2032]: Traceback (most recent call last): Apr 11 18:54:11 volumio volumio[2032]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:11 volumio volumio[2032]: from aiohttp import web Apr 11 18:54:11 volumio volumio[2032]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 18:54:12 volumio volumio[1232]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 11 18:54:12 volumio volumio[1232]: info: Received Get System Version Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 18:54:12 volumio volumio[1232]: info: Received Get System Info Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:54:12 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:54:12 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:54:12 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:54:12 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:54:12 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.221:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 18:54:12 volumio volumio[1232]: info: Discovery: Getting this device information Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Apr 11 18:54:12 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 18:54:12 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.0.222:3000 from 192.168.0.231 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 18:54:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 18:54:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 38. Apr 11 18:54:13 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:13 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:13 volumio volumio[2033]: Traceback (most recent call last): Apr 11 18:54:13 volumio volumio[2033]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:13 volumio volumio[2033]: from aiohttp import web Apr 11 18:54:13 volumio volumio[2033]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 39. Apr 11 18:54:15 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:15 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:15 volumio volumio[2048]: Traceback (most recent call last): Apr 11 18:54:15 volumio volumio[2048]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:15 volumio volumio[2048]: from aiohttp import web Apr 11 18:54:15 volumio volumio[2048]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:15 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:15 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:17 volumio go-librespot[1526]: time="2026-04-11T18:54:17+03:00" level=trace msg="sent dealer ping" Apr 11 18:54:17 volumio go-librespot[1526]: time="2026-04-11T18:54:17+03:00" level=trace msg="received dealer pong" Apr 11 18:54:18 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 40. Apr 11 18:54:18 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:18 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:18 volumio volumio[2049]: Traceback (most recent call last): Apr 11 18:54:18 volumio volumio[2049]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:18 volumio volumio[2049]: from aiohttp import web Apr 11 18:54:18 volumio volumio[2049]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:18 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:18 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:19 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 11 18:54:19 volumio volumio[1232]: info: Preload queue cleared Apr 11 18:54:20 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:20 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 41. Apr 11 18:54:20 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:20 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:20 volumio volumio[2050]: Traceback (most recent call last): Apr 11 18:54:20 volumio volumio[2050]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:20 volumio volumio[2050]: from aiohttp import web Apr 11 18:54:20 volumio volumio[2050]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:20 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:20 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:22 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:22 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 42. Apr 11 18:54:22 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:22 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:22 volumio volumio[2051]: Traceback (most recent call last): Apr 11 18:54:22 volumio volumio[2051]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:22 volumio volumio[2051]: from aiohttp import web Apr 11 18:54:22 volumio volumio[2051]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:22 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:22 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:24 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 43. Apr 11 18:54:24 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:24 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:24 volumio volumio[2052]: Traceback (most recent call last): Apr 11 18:54:24 volumio volumio[2052]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:24 volumio volumio[2052]: from aiohttp import web Apr 11 18:54:24 volumio volumio[2052]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:27 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:27 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 44. Apr 11 18:54:27 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:27 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:27 volumio volumio[2067]: Traceback (most recent call last): Apr 11 18:54:27 volumio volumio[2067]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:27 volumio volumio[2067]: from aiohttp import web Apr 11 18:54:27 volumio volumio[2067]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:27 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:27 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:27 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 11 18:54:28 volumio volumio[1232]: info: Preload queue cleared Apr 11 18:54:29 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:29 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 45. Apr 11 18:54:29 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:29 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:29 volumio volumio[2106]: Traceback (most recent call last): Apr 11 18:54:29 volumio volumio[2106]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:29 volumio volumio[2106]: from aiohttp import web Apr 11 18:54:29 volumio volumio[2106]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:29 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:29 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:31 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:31 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 46. Apr 11 18:54:31 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:31 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:31 volumio volumio[2107]: Traceback (most recent call last): Apr 11 18:54:31 volumio volumio[2107]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:31 volumio volumio[2107]: from aiohttp import web Apr 11 18:54:31 volumio volumio[2107]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:31 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:31 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 47. Apr 11 18:54:33 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:33 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:33 volumio volumio[2108]: Traceback (most recent call last): Apr 11 18:54:33 volumio volumio[2108]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:33 volumio volumio[2108]: from aiohttp import web Apr 11 18:54:33 volumio volumio[2108]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:36 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 48. Apr 11 18:54:36 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:36 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:36 volumio volumio[2123]: Traceback (most recent call last): Apr 11 18:54:36 volumio volumio[2123]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:36 volumio volumio[2123]: from aiohttp import web Apr 11 18:54:36 volumio volumio[2123]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:36 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:36 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:38 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:38 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 49. Apr 11 18:54:38 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:38 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:38 volumio volumio[2124]: Traceback (most recent call last): Apr 11 18:54:38 volumio volumio[2124]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:38 volumio volumio[2124]: from aiohttp import web Apr 11 18:54:38 volumio volumio[2124]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:38 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:38 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:40 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:40 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 50. Apr 11 18:54:40 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:40 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:40 volumio volumio[2125]: Traceback (most recent call last): Apr 11 18:54:40 volumio volumio[2125]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:40 volumio volumio[2125]: from aiohttp import web Apr 11 18:54:40 volumio volumio[2125]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:40 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:40 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:42 volumio volumio[1232]: info: Preload queue cleared Apr 11 18:54:42 volumio volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::ClearQueue Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::stop Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::clearPlayQueue Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::saveQueue Apr 11 18:54:42 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::addQueueItems Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::addQueueItems Apr 11 18:54:42 volumio volumio[1232]: info: Preload queue cleared Apr 11 18:54:42 volumio volumio[1232]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Apr 11 18:54:42 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 11 18:54:42 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::saveQueue Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::updateTrackBlock Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::getTrackBlock Apr 11 18:54:42 volumio volumio[1232]: info: CoreCommandRouter::volumioPlay Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::play index 0 Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::stop Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::play index undefined Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:42 volumio volumio[1232]: info: CoreStateMachine::startPlaybackTimer Apr 11 18:54:42 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:42 volumio volumio[1232]: info: [1775922882816] ControllerWebradio::clearAddPlayTrack Apr 11 18:54:42 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 18:54:42 volumio volumio[1232]: info: sendMpdCommand stop took 1 milliseconds Apr 11 18:54:42 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 18:54:42 volumio volumio[1232]: info: sendMpdCommand clear took 1 milliseconds Apr 11 18:54:42 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Apr 11 18:54:42 volumio volumio[1232]: info: Apr 11 18:54:42 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:42 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:42 volumio volumio[1232]: info: Apr 11 18:54:42 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:42 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:42 volumio volumio[1232]: info: Apr 11 18:54:42 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:42 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:42 volumio volumio[1232]: info: Apr 11 18:54:42 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:42 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:42 volumio volumio[1232]: error: Upnp client error: Error: This socket has been ended by the other party Apr 11 18:54:42 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 51. Apr 11 18:54:42 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:42 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:42 volumio volumio[2127]: Traceback (most recent call last): Apr 11 18:54:42 volumio volumio[2127]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:42 volumio volumio[2127]: from aiohttp import web Apr 11 18:54:42 volumio volumio[2127]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:42 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:42 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:43 volumio volumio[1232]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 306 milliseconds Apr 11 18:54:43 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 18:54:43 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand play Apr 11 18:54:43 volumio volumio[1232]: info: Apr 11 18:54:43 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:43 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 307ms Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 306ms Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 306ms Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 305ms Apr 11 18:54:43 volumio volumio[1232]: info: Apr 11 18:54:43 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:43 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:43 volumio volumio[1232]: info: Apr 11 18:54:43 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:43 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:43 volumio volumio[1232]: info: Apr 11 18:54:43 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:43 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:43 volumio volumio[1232]: info: sendMpdCommand play took 2 milliseconds Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 3ms Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 2ms Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 1ms Apr 11 18:54:43 volumio volumio[1232]: info: ------------------------------ 1ms Apr 11 18:54:44 volumio volumio[1232]: info: Apr 11 18:54:44 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:44 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:44 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:44 volumio volumio[1232]: info: Apr 11 18:54:44 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:44 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:44 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:44 volumio volumio[1232]: info: Apr 11 18:54:44 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:44 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:44 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:44 volumio volumio[1232]: info: Apr 11 18:54:44 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:44 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:44 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:44 volumio volumio[1232]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 11 18:54:44 volumio volumio[1232]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 11 18:54:44 volumio volumio[1232]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 11 18:54:44 volumio volumio[1232]: info: Apr 11 18:54:44 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:44 volumio volumio[1232]: info: sendMpdCommand status took 9 milliseconds Apr 11 18:54:44 volumio volumio[1232]: info: sendMpdCommand status took 9 milliseconds Apr 11 18:54:44 volumio volumio[1232]: info: sendMpdCommand status took 8 milliseconds Apr 11 18:54:44 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:44 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:44 volumio volumio[1232]: info: sendMpdCommand status took 8 milliseconds Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 4 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 26ms Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.024+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.024+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.025+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.026+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.026+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.026+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.026+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.026+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.026+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.027+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 39ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 39ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 38ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.052+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.052+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.052+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.052+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 55ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 52ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 52ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 52ms Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 2ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 2ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 2ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 1 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 1ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 1 milliseconds Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Apr 11 18:54:45 volumio volumio[1232]: info: Ignoring MPD Status Update Apr 11 18:54:45 volumio volumio[1232]: info: Apr 11 18:54:45 volumio volumio[1232]: ---------------------------- MPD announces state update: player Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::getState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 3ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 3 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 2ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 1 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 1ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 1 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 1ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand status took 1 milliseconds Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseState Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.086+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.086+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=0 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.086+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.086+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 23ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 23ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 23ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 23ms Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 11 18:54:45 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: ControllerMpd::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Apr 11 18:54:45 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Apr 11 18:54:45 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 11 18:54:45 volumio volumio[1232]: verbose: CURRENT POSITION 0 Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Apr 11 18:54:45 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreStateMachine::pushState Apr 11 18:54:45 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" state=STATUS_PLAYING positionMs=269 volume=33 Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55260,192.168.0.231:55444 @ 0x2db48a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio5-onboarding[1843]: time=2026-04-11T18:54:45.103+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.231:55444 @ 0x2cd8e70" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 40ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 39ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 38ms Apr 11 18:54:45 volumio volumio[1232]: info: ------------------------------ 38ms Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 11 18:54:45 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 11 18:54:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 52. Apr 11 18:54:45 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 11 18:54:45 volumio systemd[1]: Started FusionDsp Daemon. Apr 11 18:54:45 volumio volumio[2154]: Traceback (most recent call last): Apr 11 18:54:45 volumio volumio[2154]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Apr 11 18:54:45 volumio volumio[2154]: from aiohttp import web Apr 11 18:54:45 volumio volumio[2154]: ModuleNotFoundError: No module named 'aiohttp' Apr 11 18:54:45 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Apr 11 18:54:45 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 11 18:54:46 volumio volumio[1232]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 18:54:46 volumio volumio[1232]: TypeError: Cannot read property 'slice' of null Apr 11 18:54:46 volumio volumio[1232]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 11 18:54:46 volumio volumio[1232]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 11 18:54:46 volumio volumio[1232]: at Object.onceWrapper (events.js:422:26) Apr 11 18:54:46 volumio volumio[1232]: at ClientRequest.emit (events.js:315:20) Apr 11 18:54:46 volumio volumio[1232]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 11 18:54:46 volumio volumio[1232]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 11 18:54:46 volumio volumio[1232]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 11 18:54:46 volumio volumio[1232]: at TLSSocket.emit (events.js:315:20) Apr 11 18:54:46 volumio volumio[1232]: at addChunk (internal/streams/readable.js:309:12) Apr 11 18:54:46 volumio volumio[1232]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 11 18:54:46 volumio volumio[1232]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Apr 11 18:54:46 volumio volumio[1232]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 11 18:54:46 volumio volumio[1232]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 18:54:46 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-11 18:53 Apr 11 18:54:46 volumio sudo[2168]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"