-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-02-22 14:29:23 CET. -- Feb 22 14:28:00 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:00 raspberry volumio[5264]: Traceback (most recent call last): Feb 22 14:28:00 raspberry volumio[5264]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:00 raspberry volumio[5264]: from aiohttp import web Feb 22 14:28:00 raspberry volumio[5264]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:00 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:00 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:02 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:02 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 344. Feb 22 14:28:02 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:02 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:02 raspberry volumio[5265]: Traceback (most recent call last): Feb 22 14:28:02 raspberry volumio[5265]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:02 raspberry volumio[5265]: from aiohttp import web Feb 22 14:28:02 raspberry volumio[5265]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:02 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:02 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:04 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:04 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 345. Feb 22 14:28:04 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:04 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:04 raspberry volumio[5269]: Traceback (most recent call last): Feb 22 14:28:04 raspberry volumio[5269]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:04 raspberry volumio[5269]: from aiohttp import web Feb 22 14:28:04 raspberry volumio[5269]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:04 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:04 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:06 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:06 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 346. Feb 22 14:28:06 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:06 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:06 raspberry volumio[5285]: Traceback (most recent call last): Feb 22 14:28:06 raspberry volumio[5285]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:06 raspberry volumio[5285]: from aiohttp import web Feb 22 14:28:06 raspberry volumio[5285]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:06 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:06 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:08 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:08 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 347. Feb 22 14:28:09 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:09 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:09 raspberry volumio[5286]: Traceback (most recent call last): Feb 22 14:28:09 raspberry volumio[5286]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:09 raspberry volumio[5286]: from aiohttp import web Feb 22 14:28:09 raspberry volumio[5286]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:09 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:09 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:09 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:11 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:11 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 348. Feb 22 14:28:11 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:11 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:11 raspberry volumio[5328]: Traceback (most recent call last): Feb 22 14:28:11 raspberry volumio[5328]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:11 raspberry volumio[5328]: from aiohttp import web Feb 22 14:28:11 raspberry volumio[5328]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:11 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:11 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: info: Received OAUTH Data Feb 22 14:28:12 raspberry volumio[1096]: info: Executing Spotify Oauth Login Feb 22 14:28:12 raspberry volumio[1096]: info: Saving Spotify Refresh Token Feb 22 14:28:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:12 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:12 raspberry volumio[1096]: SPOTIFY: BQCgWMyVNFZY_FzJCiEcdFTCdDnZ180i8Ek9aU7IIodTOShSMxjElfQdJknT4cZRYM6Hk-CrzBEi73NEXeOKmGA-0MO6pLlt9QZvvSY6wfCRG1AbHDalbCFx3q2ca2gUscK4LxprSzPwI2gp1qLbBPQB5tog2JlhOrev3ByW7cPzBIEkmwjCABjMCN3TNdYCGEvX31n4tOPOOphItUOp21MaK1n2Vm_UNKGx2IETwYG2c4I8S1RXFAZ7dvqUTDwv0yD1y1rv4lDy0_7wRaNj_sRqyux07NjlMUuZ4Xc Feb 22 14:28:12 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:12 raspberry volumio[1096]: info: New Spotify access token = BQCgWMyVNFZY_FzJCiEcdFTCdDnZ180i8Ek9aU7IIodTOShSMxjElfQdJknT4cZRYM6Hk-CrzBEi73NEXeOKmGA-0MO6pLlt9QZvvSY6wfCRG1AbHDalbCFx3q2ca2gUscK4LxprSzPwI2gp1qLbBPQB5tog2JlhOrev3ByW7cPzBIEkmwjCABjMCN3TNdYCGEvX31n4tOPOOphItUOp21MaK1n2Vm_UNKGx2IETwYG2c4I8S1RXFAZ7dvqUTDwv0yD1y1rv4lDy0_7wRaNj_sRqyux07NjlMUuZ4Xc Feb 22 14:28:12 raspberry volumio[1096]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 22 14:28:13 raspberry sudo[5333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:28:13 raspberry sudo[5333]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:13 raspberry sudo[5333]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:13 raspberry sudo[5336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:28:13 raspberry sudo[5336]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:13 raspberry sudo[5336]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:13 raspberry volumio[1096]: SPOTIFY: User informations: {"country":"PL","display_name":"Pioter","email":"pioter@pioter.int.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cwqsmkugwnj4rwokq5kutxacza"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cwqsmkugwnj4rwokq5kutxacza","id":"31cwqsmkugwnj4rwokq5kutxacza","images":[],"product":"free","type":"user","uri":"spotify:user:31cwqsmkugwnj4rwokq5kutxacza"} Feb 22 14:28:13 raspberry volumio[1096]: info: Creating Spotify config file Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:28:13 raspberry volumio[1096]: verbose: New Socket.io Connection to 192.168.100.38 from 192.168.100.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 22 14:28:13 raspberry volumio[1096]: info: Spotify config file written Feb 22 14:28:13 raspberry sudo[5339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 14:28:13 raspberry sudo[5339]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:13 raspberry systemd[1]: Stopping go-librespot Daemon... Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Feb 22 14:28:13 raspberry systemd[1]: go-librespot-daemon.service: Succeeded. Feb 22 14:28:13 raspberry systemd[1]: Stopped go-librespot Daemon. Feb 22 14:28:13 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:13 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry systemd[1]: Started go-librespot Daemon. Feb 22 14:28:13 raspberry go-librespot[5341]: go-librespot daemon starting... Feb 22 14:28:13 raspberry sudo[5339]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="app state loaded" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="stored credentials not found" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:28:13 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:13 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry volumio[1096]: info: Listing playlists Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:28:13 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:13 raspberry volumio[1096]: SPOTIFY: BQBy5M8-sdzMu-lUrbtmo6cba9L0Y-o5dQ8IspirKRp3Q7P1LxHn3N_etSzp-dJWAEY9vYV-DofuJeMMJXT3ue1IO1yYPkqx_PYpXPh_FPoFBru6q596Hny6VeJ4jEklTcw0M5G_ZowwjMRehuFPgvfQXppFvDtYGPGpB-kDpNVAOWb4ACtrvZ6ZifR6dkRMepbcJvbYiOZVRbXZjxLK6id13e_j0e2IQrDQFQsvZN5Y0JFxmngEPa2Di41z3I-gmCEnVPc78ieid0OY0vKCGbOUMklJ5gDKI720mVU Feb 22 14:28:13 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:13 raspberry volumio[1096]: info: New Spotify access token = BQBy5M8-sdzMu-lUrbtmo6cba9L0Y-o5dQ8IspirKRp3Q7P1LxHn3N_etSzp-dJWAEY9vYV-DofuJeMMJXT3ue1IO1yYPkqx_PYpXPh_FPoFBru6q596Hny6VeJ4jEklTcw0M5G_ZowwjMRehuFPgvfQXppFvDtYGPGpB-kDpNVAOWb4ACtrvZ6ZifR6dkRMepbcJvbYiOZVRbXZjxLK6id13e_j0e2IQrDQFQsvZN5Y0JFxmngEPa2Di41z3I-gmCEnVPc78ieid0OY0vKCGbOUMklJ5gDKI720mVU Feb 22 14:28:13 raspberry volumio[1096]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=info msg="zeroconf server listening on port 39047" Feb 22 14:28:13 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:13 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 349. Feb 22 14:28:13 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:13 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="obtained new client token: AAC1/eThKUyZzzlW54A2TjXSS5AIkxZX39dwN8xuAqaovTeOoRgdVh9DepysxhVDu1K3qwHt8Lxmp2/oWQjtH6KCYv5kClrZjnrbUwKumM2INdBJwqv0vYT2uvFIoHfPrff5NTddNbD2CiZdLXmp4+ZEO8uPJmRGEdNF5adkPV6SP0CqZ3pUdoRI2JPQHq449PUosRP4RwkuDyk8rJEajZjy44rwjpHi0pOhCaYC35ky4Sinrl7l7ffIBQ==" Feb 22 14:28:13 raspberry go-librespot[5341]: time="2025-02-22T14:28:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 14:28:13 raspberry volumio[5349]: Traceback (most recent call last): Feb 22 14:28:13 raspberry volumio[5349]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:13 raspberry volumio[5349]: from aiohttp import web Feb 22 14:28:13 raspberry volumio[5349]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:13 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:13 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:13 raspberry volumio[1096]: SPOTIFY: User informations: {"country":"PL","display_name":"Pioter","email":"pioter@pioter.int.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cwqsmkugwnj4rwokq5kutxacza"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cwqsmkugwnj4rwokq5kutxacza","id":"31cwqsmkugwnj4rwokq5kutxacza","images":[],"product":"free","type":"user","uri":"spotify:user:31cwqsmkugwnj4rwokq5kutxacza"} Feb 22 14:28:13 raspberry volumio[1096]: info: Spotify Successfully logged in Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 14:28:13 raspberry volumio[1096]: info: [1740230893826] CoreMusicLibrary::Adding element Spotify Feb 22 14:28:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:13 raspberry volumio[1096]: Cannot find translation for source Spotify Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="completed keyexchange" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="completed challenge" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=info msg="authenticated AP as 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=info msg="authenticated Login5 as 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="stored credentials for 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="initializing zeroconf session, username: 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="dealer connection opened" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=trace msg="starting accesspoint recv loop" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=trace msg="starting dealer recv loop" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=trace msg="received accesspoint ping" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="received connection id: ZjhmMzUzMjctZmFkMy00ZGQzLWFlMjktOGJkYzFlOThmMTM4K2RlYWxlcit0Y3A6Ly8wYWNhNTg5OS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNzk5Njg1ODU2QUY0MDAyMjBDNjY2RUZBNUNGOUI2QjMxRkZCNkM3QUYxMzUwQjIyRjc1RUIxQjA0MDI5QjNDRg==" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=trace msg="received accesspoint pong ack" Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 22 14:28:14 raspberry go-librespot[5341]: time="2025-02-22T14:28:14+01:00" level=debug msg="update volume to 65535/65535" Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:14 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:14 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:15 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 350. Feb 22 14:28:15 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:15 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:15 raspberry volumio[5350]: Traceback (most recent call last): Feb 22 14:28:15 raspberry volumio[5350]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:15 raspberry volumio[5350]: from aiohttp import web Feb 22 14:28:15 raspberry volumio[5350]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:15 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:15 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:15 raspberry go-librespot[5341]: time="2025-02-22T14:28:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 22 14:28:15 raspberry go-librespot[5341]: time="2025-02-22T14:28:15+01:00" level=trace msg="emitting websocket event: volume" Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:16 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:16 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:16 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:16 raspberry go-librespot[5341]: time="2025-02-22T14:28:16+01:00" level=debug msg="new websocket client" Feb 22 14:28:16 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:16 raspberry volumio[1096]: info: go-librespot daemon successfully initialized Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:17 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:17 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 351. Feb 22 14:28:18 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:18 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:18 raspberry volumio[5365]: Traceback (most recent call last): Feb 22 14:28:18 raspberry volumio[5365]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:18 raspberry volumio[5365]: from aiohttp import web Feb 22 14:28:18 raspberry volumio[5365]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:18 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:18 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:19 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:19 raspberry volumio[1096]: info: Spotify volume: 100 Feb 22 14:28:19 raspberry go-librespot[5341]: time="2025-02-22T14:28:19+01:00" level=debug msg="new websocket client" Feb 22 14:28:19 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:19 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: CALLMETHOD: music_service spop logout Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: spop , logout Feb 22 14:28:19 raspberry volumio[1096]: info: Deleting Spotify credentials File Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:20 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 352. Feb 22 14:28:20 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:20 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:20 raspberry volumio[5366]: Traceback (most recent call last): Feb 22 14:28:20 raspberry volumio[5366]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:20 raspberry volumio[5366]: from aiohttp import web Feb 22 14:28:20 raspberry volumio[5366]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:20 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry volumio[1096]: info: Creating Spotify config file Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:28:20 raspberry volumio[1096]: info: Spotify config file written Feb 22 14:28:20 raspberry sudo[5368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 14:28:20 raspberry sudo[5368]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:20 raspberry systemd[1]: Stopping go-librespot Daemon... Feb 22 14:28:20 raspberry systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Feb 22 14:28:20 raspberry systemd[1]: go-librespot-daemon.service: Succeeded. Feb 22 14:28:20 raspberry systemd[1]: Stopped go-librespot Daemon. Feb 22 14:28:20 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:20 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:20 raspberry systemd[1]: Started go-librespot Daemon. Feb 22 14:28:20 raspberry go-librespot[5370]: go-librespot daemon starting... Feb 22 14:28:20 raspberry sudo[5368]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=debug msg="no app state found" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=debug msg="stored credentials not found" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=info msg="generated new device id: c6e627f7494f2d06a79111ca58c3f1b2e146117a" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 14:28:20 raspberry go-librespot[5370]: time="2025-02-22T14:28:20+01:00" level=info msg="zeroconf server listening on port 36165" Feb 22 14:28:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:22 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:22 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 353. Feb 22 14:28:22 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:22 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[5378]: Traceback (most recent call last): Feb 22 14:28:22 raspberry volumio[5378]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:22 raspberry volumio[5378]: from aiohttp import web Feb 22 14:28:22 raspberry volumio[5378]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:22 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:22 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:22 raspberry volumio[1096]: info: Prefetching next song Feb 22 14:28:22 raspberry volumio[1096]: info: [1740230902681] ControllerSpotify::prefetch Feb 22 14:28:22 raspberry volumio[1096]: info: Sending Spotify command with payload to local API: /player/add_to_queue Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:23 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:23 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:23 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:23 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:23 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:23 raspberry go-librespot[5370]: time="2025-02-22T14:28:23+01:00" level=debug msg="new websocket client" Feb 22 14:28:23 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:23 raspberry volumio[1096]: info: go-librespot daemon successfully initialized Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:23 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:23 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:23 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:24 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:24 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:24 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:24 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:24 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:24 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 354. Feb 22 14:28:24 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:24 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:24 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:24 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:24 raspberry volumio[5382]: Traceback (most recent call last): Feb 22 14:28:24 raspberry volumio[5382]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:24 raspberry volumio[5382]: from aiohttp import web Feb 22 14:28:24 raspberry volumio[5382]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:24 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:24 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:25 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:25 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:26 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:26 raspberry go-librespot[5370]: time="2025-02-22T14:28:26+01:00" level=debug msg="new websocket client" Feb 22 14:28:26 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:26 raspberry volumio[1096]: info: CorePlayQueue::getTrack 2 Feb 22 14:28:26 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:26 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 355. Feb 22 14:28:27 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:27 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:27 raspberry volumio[5397]: Traceback (most recent call last): Feb 22 14:28:27 raspberry volumio[5397]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:27 raspberry volumio[5397]: from aiohttp import web Feb 22 14:28:27 raspberry volumio[5397]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:27 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:27 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:27 raspberry volumio[1096]: info: CoreStateMachine::startPlaybackTimer Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreStateMachine::pushState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioPushState Feb 22 14:28:27 raspberry volumio[1096]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 22 14:28:27 raspberry sudo[5399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 22 14:28:27 raspberry sudo[5399]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:27 raspberry sudo[5399]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:27 raspberry volumio[1096]: info: peppymeterbasic Daemon Stop Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:27 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:27 raspberry volumio[1096]: info: Received OAUTH Data Feb 22 14:28:27 raspberry volumio[1096]: info: Executing Spotify Oauth Login Feb 22 14:28:27 raspberry volumio[1096]: info: Saving Spotify Refresh Token Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: BQB2wRoAWWHFyMfMalrV6ACTKgpxLD0_4be12gm2yVxQCJFat1UWe_t81EuQaOCKsxU-ZdxbDJkNF5AuqICIKmt756Tpei36QRu9YBEFX_IYI1RvZ0ehkwp5-_nZY1_m7TJKxOY_k0W1IVo34TPGiSllQzAPqKkaDigzF8ysxCWX8zxK-s2s-tLf2vuyuqimK1spocRnba3JmhaemuoYlUNS7bAl5JltEtZXO6aiDpA88mBlKP6n-8yWSJqK75EXj89q3HvLHFFiNiCuWQEyKoj08TSEwxNgep6YrC0 Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:28 raspberry volumio[1096]: info: New Spotify access token = BQB2wRoAWWHFyMfMalrV6ACTKgpxLD0_4be12gm2yVxQCJFat1UWe_t81EuQaOCKsxU-ZdxbDJkNF5AuqICIKmt756Tpei36QRu9YBEFX_IYI1RvZ0ehkwp5-_nZY1_m7TJKxOY_k0W1IVo34TPGiSllQzAPqKkaDigzF8ysxCWX8zxK-s2s-tLf2vuyuqimK1spocRnba3JmhaemuoYlUNS7bAl5JltEtZXO6aiDpA88mBlKP6n-8yWSJqK75EXj89q3HvLHFFiNiCuWQEyKoj08TSEwxNgep6YrC0 Feb 22 14:28:28 raspberry volumio[1096]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 22 14:28:28 raspberry sudo[5402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 14:28:28 raspberry sudo[5402]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:28 raspberry sudo[5402]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry sudo[5405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry sudo[5405]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: User informations: {"country":"PL","display_name":"Pioter","email":"pioter@pioter.int.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cwqsmkugwnj4rwokq5kutxacza"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cwqsmkugwnj4rwokq5kutxacza","id":"31cwqsmkugwnj4rwokq5kutxacza","images":[],"product":"free","type":"user","uri":"spotify:user:31cwqsmkugwnj4rwokq5kutxacza"} Feb 22 14:28:28 raspberry volumio[1096]: info: Creating Spotify config file Feb 22 14:28:28 raspberry sudo[5405]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:28:28 raspberry volumio[1096]: info: Spotify config file written Feb 22 14:28:28 raspberry sudo[5408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 14:28:28 raspberry sudo[5408]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:28 raspberry volumio[1096]: verbose: New Socket.io Connection to 192.168.100.38 from 192.168.100.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 22 14:28:28 raspberry systemd[1]: Stopping go-librespot Daemon... Feb 22 14:28:28 raspberry systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Feb 22 14:28:28 raspberry systemd[1]: go-librespot-daemon.service: Succeeded. Feb 22 14:28:28 raspberry systemd[1]: Stopped go-librespot Daemon. Feb 22 14:28:28 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:28 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:28:28 raspberry systemd[1]: Started go-librespot Daemon. Feb 22 14:28:28 raspberry go-librespot[5410]: go-librespot daemon starting... Feb 22 14:28:28 raspberry sudo[5408]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="app state loaded" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="stored credentials not found" Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:28:28 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:28 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 14:28:28 raspberry volumio[1096]: info: Listing playlists Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: BQCX2rGMOj-ONwYGZQd_lluM8xn7oO96D1uPASbfsv1kItvWTpDTzGhLdeZW5kkn4oMMctaYXm0M3ZZls59W3BCTezzduxDJddTtVfc9-0DbZqsHDXFxB0sNoNSslVauCRZE6v_m0OOn_4Tf7q-36MbhGv1QTUalsXdNXAfWU0MlHefKz6BLfxqAOn5dsFIWDpUwBgcLbI7zRJDM0Q3QrCiZ-DN_kKEbNOIGji5UAPIboCE1RiqVP1SoZemc7ATvafwZ_8iKWxz54M4kLPhQXz1lIP3cU6WSx_rgQ2E Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 22 14:28:28 raspberry volumio[1096]: info: New Spotify access token = BQCX2rGMOj-ONwYGZQd_lluM8xn7oO96D1uPASbfsv1kItvWTpDTzGhLdeZW5kkn4oMMctaYXm0M3ZZls59W3BCTezzduxDJddTtVfc9-0DbZqsHDXFxB0sNoNSslVauCRZE6v_m0OOn_4Tf7q-36MbhGv1QTUalsXdNXAfWU0MlHefKz6BLfxqAOn5dsFIWDpUwBgcLbI7zRJDM0Q3QrCiZ-DN_kKEbNOIGji5UAPIboCE1RiqVP1SoZemc7ATvafwZ_8iKWxz54M4kLPhQXz1lIP3cU6WSx_rgQ2E Feb 22 14:28:28 raspberry volumio[1096]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=info msg="zeroconf server listening on port 44443" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="obtained new client token: AABmYanz16OmAM9btC5NBjtrU5VmS6EmM/Tkj6YmBFeBMa8cC+ydX5EPzYkgElujlqUJZn+oRiAF0BVo/Wx9yRX4nywPin41xd3+P2Gf57m7vF6pAC1RvTQ/VX1JeEBSMBlCDySjpSgVVj4kr6QfTptuQkAtgskto+QGNcQhZMZcAwihRmwVoIXAhP8U8lg68zCR9yBspzuNB6+t0YWuOojNX5cRtpDAcGem6LCru1eIUoObso+IOqvdiw==" Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:28 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:28 raspberry volumio[1096]: SPOTIFY: User informations: {"country":"PL","display_name":"Pioter","email":"pioter@pioter.int.pl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cwqsmkugwnj4rwokq5kutxacza"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cwqsmkugwnj4rwokq5kutxacza","id":"31cwqsmkugwnj4rwokq5kutxacza","images":[],"product":"free","type":"user","uri":"spotify:user:31cwqsmkugwnj4rwokq5kutxacza"} Feb 22 14:28:28 raspberry volumio[1096]: info: Spotify Successfully logged in Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 14:28:28 raspberry volumio[1096]: info: [1740230908786] CoreMusicLibrary::Adding element Spotify Feb 22 14:28:28 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:28 raspberry volumio[1096]: Cannot find translation for source Spotify Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="completed keyexchange" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=debug msg="completed challenge" Feb 22 14:28:28 raspberry go-librespot[5410]: time="2025-02-22T14:28:28+01:00" level=info msg="authenticated AP as 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=info msg="authenticated Login5 as 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="stored credentials for 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="initializing zeroconf session, username: 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="dealer connection opened" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=trace msg="starting accesspoint recv loop" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=trace msg="starting dealer recv loop" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=trace msg="received accesspoint ping" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="received connection id: NDcyNjAzMzgtOTFlNy00NTUxLWExNDItZGRkZGEyOTRlMDdjK2RlYWxlcit0Y3A6Ly8wYWNhNTk4Yi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArOTYyMjdGODIzNjI1MkUzMEY5NEIyQ0MwODFFMTc2MzZCM0RFRkFFODRFODJEQzkzMzI1M0UzQUQzRjVDNzMwNA==" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=trace msg="received accesspoint pong ack" Feb 22 14:28:29 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:29 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 356. Feb 22 14:28:29 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 22 14:28:29 raspberry go-librespot[5410]: time="2025-02-22T14:28:29+01:00" level=debug msg="update volume to 65535/65535" Feb 22 14:28:29 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:29 raspberry volumio[5418]: Traceback (most recent call last): Feb 22 14:28:29 raspberry volumio[5418]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:29 raspberry volumio[5418]: from aiohttp import web Feb 22 14:28:29 raspberry volumio[5418]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:29 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:29 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:29 raspberry volumio[1096]: info: Spotify volume: 100 Feb 22 14:28:29 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:29 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:29 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:30 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:30 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry go-librespot[5410]: time="2025-02-22T14:28:30+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 22 14:28:30 raspberry go-librespot[5410]: time="2025-02-22T14:28:30+01:00" level=trace msg="emitting websocket event: volume" Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:30 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:30 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: verbose: New Socket.io Connection to 192.168.100.38 from 192.168.100.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:28:31 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:31 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: Listing playlists Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:31 raspberry go-librespot[5410]: time="2025-02-22T14:28:31+01:00" level=debug msg="new websocket client" Feb 22 14:28:31 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:31 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:31 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 357. Feb 22 14:28:31 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:31 raspberry volumio[1096]: info: go-librespot daemon successfully initialized Feb 22 14:28:31 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:28:31 raspberry volumio[5420]: Traceback (most recent call last): Feb 22 14:28:31 raspberry volumio[5420]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:31 raspberry volumio[5420]: from aiohttp import web Feb 22 14:28:31 raspberry volumio[5420]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:31 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:31 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:31 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:32 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:32 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:32 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:32 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:32 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:32 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:32 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:33 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:33 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:33 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 358. Feb 22 14:28:33 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:33 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:33 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:33 raspberry volumio[5424]: Traceback (most recent call last): Feb 22 14:28:33 raspberry volumio[5424]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:33 raspberry volumio[5424]: from aiohttp import web Feb 22 14:28:33 raspberry volumio[5424]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:33 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:33 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:33 raspberry volumio[1096]: verbose: New Socket.io Connection to 192.168.100.38 from 192.168.100.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 14:28:34 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:34 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: Listing playlists Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:34 raspberry volumio[1096]: info: Spotify volume: 100 Feb 22 14:28:34 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:34 raspberry go-librespot[5410]: time="2025-02-22T14:28:34+01:00" level=debug msg="new websocket client" Feb 22 14:28:34 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:34 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:34 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:35 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:35 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:35 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:35 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:35 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:35 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 359. Feb 22 14:28:36 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:36 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:36 raspberry volumio[5426]: Traceback (most recent call last): Feb 22 14:28:36 raspberry volumio[5426]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:36 raspberry volumio[5426]: from aiohttp import web Feb 22 14:28:36 raspberry volumio[5426]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:36 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:36 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:36 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:36 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:36 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:36 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:37 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:37 raspberry volumio[1096]: info: Spotify volume: 100 Feb 22 14:28:37 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:37 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:37 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:38 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 360. Feb 22 14:28:38 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:38 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[5441]: Traceback (most recent call last): Feb 22 14:28:38 raspberry volumio[5441]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:38 raspberry volumio[5441]: from aiohttp import web Feb 22 14:28:38 raspberry volumio[5441]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:38 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:38 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:38 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:38 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:39 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:39 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:39 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:40 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:40 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:40 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:40 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:40 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:40 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 361. Feb 22 14:28:40 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:40 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:40 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:40 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:40 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:40 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:40 raspberry volumio[5442]: Traceback (most recent call last): Feb 22 14:28:40 raspberry volumio[5442]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:40 raspberry volumio[5442]: from aiohttp import web Feb 22 14:28:40 raspberry volumio[5442]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:40 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:40 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:40 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:40 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:40 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:40 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:41 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:41 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:41 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:41 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:41 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:41 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:41 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:41 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:41 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:41 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:41 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:41 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:42 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:42 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:42 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:42 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:42 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:42 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:42 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:42 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 362. Feb 22 14:28:42 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:42 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:42 raspberry volumio[5443]: Traceback (most recent call last): Feb 22 14:28:42 raspberry volumio[5443]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:42 raspberry volumio[5443]: from aiohttp import web Feb 22 14:28:42 raspberry volumio[5443]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:42 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:42 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry volumio[1096]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Feb 22 14:28:43 raspberry volumio[1096]: info: Creating Spotify config file Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 14:28:43 raspberry volumio[1096]: info: Spotify config file written Feb 22 14:28:43 raspberry sudo[5448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 14:28:43 raspberry sudo[5448]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:28:43 raspberry systemd[1]: Stopping go-librespot Daemon... Feb 22 14:28:43 raspberry systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Feb 22 14:28:43 raspberry systemd[1]: go-librespot-daemon.service: Succeeded. Feb 22 14:28:43 raspberry systemd[1]: Stopped go-librespot Daemon. Feb 22 14:28:43 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:43 raspberry volumio[1096]: info: Connection to go-librespot Websocket closed Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry systemd[1]: Started go-librespot Daemon. Feb 22 14:28:43 raspberry go-librespot[5450]: go-librespot daemon starting... Feb 22 14:28:43 raspberry sudo[5448]: pam_unix(sudo:session): session closed for user root Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="app state loaded" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=info msg="zeroconf server listening on port 42439" Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="obtained new client token: AACQ7HZfBvivHfVq7nara9ID+3oenB8UtugiuZR465WiI2A04wBxCL2PoY/JFLhCLgbK79CHNazEfw9QTZiJKP9KS3Ymd2xhB0dIC4KhLiUredJ9h/G3LoW/2GBXmeCCLjghBhw+RiwXEuFgkNZX/C0DSvHfHioYw3hyLOWVFkSrVUq/EttBVzWPSP4Ovc1opfT7nWhCvPdvyaaL1LUPQX+ra54gjftObVMTPFL7bqT65ZKiRzdzbtn7+g==" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="completed keyexchange" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=debug msg="completed challenge" Feb 22 14:28:43 raspberry go-librespot[5450]: time="2025-02-22T14:28:43+01:00" level=info msg="authenticated AP as 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:43 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:43 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=info msg="authenticated Login5 as 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="initializing zeroconf session, username: 31cwqsmkugwnj4rwokq5kutxacza" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="dealer connection opened" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=trace msg="starting accesspoint recv loop" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=trace msg="starting dealer recv loop" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=trace msg="received accesspoint ping" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="received connection id: MzQ2OTA2YzQtNTdlMi00ZTYyLTgxNWEtYzhlMDNkNWE1NDYzK2RlYWxlcit0Y3A6Ly8wYWNhNTkyMC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNDRERUE5NjdBNTA4OUI2REVGQjg4NUY5MkI4OEMxNjU0QTVGRjk3ODFFOUZGOEEyNTIxREM4MEYwNTFBRDQ2Mg==" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=trace msg="received accesspoint pong ack" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 22 14:28:44 raspberry go-librespot[5450]: time="2025-02-22T14:28:44+01:00" level=debug msg="update volume to 65535/65535" Feb 22 14:28:44 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:44 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:44 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:44 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:44 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:44 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:44 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:44 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:44 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 363. Feb 22 14:28:45 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:45 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:45 raspberry volumio[5458]: Traceback (most recent call last): Feb 22 14:28:45 raspberry volumio[5458]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:45 raspberry volumio[5458]: from aiohttp import web Feb 22 14:28:45 raspberry volumio[5458]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:45 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:45 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:45 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:45 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:45 raspberry go-librespot[5450]: time="2025-02-22T14:28:45+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 22 14:28:45 raspberry go-librespot[5450]: time="2025-02-22T14:28:45+01:00" level=trace msg="emitting websocket event: volume" Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:45 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:45 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 14:28:45 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:28:46 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:46 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:46 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:46 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:46 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:46 raspberry go-librespot[5450]: time="2025-02-22T14:28:46+01:00" level=debug msg="new websocket client" Feb 22 14:28:46 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:46 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:46 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:46 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:46 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:46 raspberry volumio[1096]: info: go-librespot daemon successfully initialized Feb 22 14:28:46 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:46 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:46 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:46 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:47 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 364. Feb 22 14:28:47 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:47 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[5473]: Traceback (most recent call last): Feb 22 14:28:47 raspberry volumio[5473]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:47 raspberry volumio[5473]: from aiohttp import web Feb 22 14:28:47 raspberry volumio[5473]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:47 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:47 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:47 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:47 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:48 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:48 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:48 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:48 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:48 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:48 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:48 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:48 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:48 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:48 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:48 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:48 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:49 raspberry volumio[1096]: info: Spotify volume: 100 Feb 22 14:28:49 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry volumio[1096]: info: Initializing connection to go-librespot Websocket Feb 22 14:28:49 raspberry go-librespot[5450]: time="2025-02-22T14:28:49+01:00" level=debug msg="new websocket client" Feb 22 14:28:49 raspberry volumio[1096]: info: Connection to go-librespot Websocket established Feb 22 14:28:49 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:49 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 365. Feb 22 14:28:49 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:49 raspberry volumio[5474]: Traceback (most recent call last): Feb 22 14:28:49 raspberry volumio[5474]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:49 raspberry volumio[5474]: from aiohttp import web Feb 22 14:28:49 raspberry volumio[5474]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:49 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:49 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:49 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:49 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:50 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:50 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:50 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:50 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:50 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:50 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:50 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:50 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:50 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:50 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:50 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:50 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:51 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:51 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:51 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:51 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:51 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:51 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:51 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:51 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 366. Feb 22 14:28:51 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:51 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:51 raspberry volumio[5475]: Traceback (most recent call last): Feb 22 14:28:51 raspberry volumio[5475]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:51 raspberry volumio[5475]: from aiohttp import web Feb 22 14:28:51 raspberry volumio[5475]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:51 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:51 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 14:28:52 raspberry volumio[1096]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 14:28:52 raspberry volumio[1096]: info: Received Get System Version Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 14:28:52 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:52 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: Getting Spotify volume Feb 22 14:28:52 raspberry volumio[1096]: info: Spotify volume: 100 Feb 22 14:28:52 raspberry volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:52 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:52 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:53 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:53 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:53 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 367. Feb 22 14:28:54 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:54 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:54 raspberry volumio[5479]: Traceback (most recent call last): Feb 22 14:28:54 raspberry volumio[5479]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:54 raspberry volumio[5479]: from aiohttp import web Feb 22 14:28:54 raspberry volumio[5479]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:54 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:54 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:54 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:54 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:54 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:54 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:54 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:54 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:54 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:54 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:54 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:54 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:54 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:54 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:55 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:55 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:55 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:55 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:55 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:55 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:55 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:55 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:55 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:55 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:55 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:55 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:56 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 368. Feb 22 14:28:56 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:56 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:56 raspberry volumio[5480]: Traceback (most recent call last): Feb 22 14:28:56 raspberry volumio[5480]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:56 raspberry volumio[5480]: from aiohttp import web Feb 22 14:28:56 raspberry volumio[5480]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:56 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:56 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreCommandRouter::volumioPlay Feb 22 14:28:56 raspberry volumio[1096]: info: CoreStateMachine::play index undefined Feb 22 14:28:56 raspberry volumio[1096]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: CoreStateMachine::startPlaybackTimer Feb 22 14:28:56 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:56 raspberry volumio[1096]: info: [1740230936956] ControllerSpotify::clearAddPlayTrack Feb 22 14:28:56 raspberry volumio[1096]: info: Sending Spotify command with payload to local API: /player/play Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=debug msg="resolved context of track" uri="spotify:track:4laCtGHEZEupZ7uEDQH2Ae" Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4laCtGHEZEupZ7uEDQH2Ae" Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4laCtGHEZEupZ7uEDQH2Ae" Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=trace msg="emitting websocket event: will_play" Feb 22 14:28:57 raspberry volumio[1096]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4laCtGHEZEupZ7uEDQH2Ae","play_origin":"go-librespot"}} Feb 22 14:28:57 raspberry volumio[1096]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4laCtGHEZEupZ7uEDQH2Ae","play_origin":"go-librespot"}} Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=debug msg="selected format OGG_VORBIS_320 (1fa37e6329b45e1e024d7b68deb3b890d40a048d)" uri="spotify:track:4laCtGHEZEupZ7uEDQH2Ae" Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=debug msg="requested aes key for file 1fa37e6329b45e1e024d7b68deb3b890d40a048d, gid: 4laCtGHEZEupZ7uEDQH2Ae" Feb 22 14:28:57 raspberry go-librespot[5450]: time="2025-02-22T14:28:57+01:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:4laCtGHEZEupZ7uEDQH2Ae: failed retrieving audio key: failed retrieving aes key with code 1" Feb 22 14:28:57 raspberry volumio[1096]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:57 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:57 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:58 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:58 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:58 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:58 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:58 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:28:58 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 369. Feb 22 14:28:58 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:28:58 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:28:58 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:58 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:58 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:58 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:58 raspberry volumio[5495]: Traceback (most recent call last): Feb 22 14:28:58 raspberry volumio[5495]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:28:58 raspberry volumio[5495]: from aiohttp import web Feb 22 14:28:58 raspberry volumio[5495]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:28:58 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:28:58 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:28:58 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:58 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:58 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:58 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 14:28:59 raspberry volumio[1096]: info: Received Get System Info Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:28:59 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:28:59 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:28:59 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:00 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 370. Feb 22 14:29:00 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:00 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:00 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:00 raspberry volumio[5496]: Traceback (most recent call last): Feb 22 14:29:00 raspberry volumio[5496]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:00 raspberry volumio[5496]: from aiohttp import web Feb 22 14:29:00 raspberry volumio[5496]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:00 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:00 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetQueue Feb 22 14:29:01 raspberry volumio[1096]: info: CoreStateMachine::getQueue Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getQueue Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:01 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:01 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 22 14:29:02 raspberry volumio[1096]: info: In handleBrowseUri, curUri=spotify Feb 22 14:29:02 raspberry volumio[1096]: info: Preload queue cleared Feb 22 14:29:02 raspberry volumio[1096]: info: Preload queue cleared Feb 22 14:29:02 raspberry volumio[1096]: info: Preload queue cleared Feb 22 14:29:02 raspberry volumio[1096]: info: Preload queue cleared Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:02 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:02 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:02 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 371. Feb 22 14:29:03 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:03 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:03 raspberry volumio[5500]: Traceback (most recent call last): Feb 22 14:29:03 raspberry volumio[5500]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:03 raspberry volumio[5500]: from aiohttp import web Feb 22 14:29:03 raspberry volumio[5500]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:03 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:03 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:03 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:03 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:04 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:04 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:05 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:05 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 372. Feb 22 14:29:05 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:05 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:05 raspberry volumio[5501]: Traceback (most recent call last): Feb 22 14:29:05 raspberry volumio[5501]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:05 raspberry volumio[5501]: from aiohttp import web Feb 22 14:29:05 raspberry volumio[5501]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:05 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:05 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:05 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:05 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:06 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:06 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:07 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 373. Feb 22 14:29:07 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:07 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:07 raspberry volumio[5517]: Traceback (most recent call last): Feb 22 14:29:07 raspberry volumio[5517]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:07 raspberry volumio[5517]: from aiohttp import web Feb 22 14:29:07 raspberry volumio[5517]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:07 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:07 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:07 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:07 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:08 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:08 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 14:29:09 raspberry volumio[1096]: info: Discovery: Getting this device information Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:09 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 374. Feb 22 14:29:09 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:09 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:09 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:09 raspberry volumio[5559]: Traceback (most recent call last): Feb 22 14:29:09 raspberry volumio[5559]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:09 raspberry volumio[5559]: from aiohttp import web Feb 22 14:29:09 raspberry volumio[5559]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:09 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:09 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:10 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:10 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:11 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:11 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:11 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 375. Feb 22 14:29:12 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:12 raspberry volumio[5560]: Traceback (most recent call last): Feb 22 14:29:12 raspberry volumio[5560]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:12 raspberry volumio[5560]: from aiohttp import web Feb 22 14:29:12 raspberry volumio[5560]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:12 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:12 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:12 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:12 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:29:13 raspberry volumio[1096]: info: Retrieving Cloud Streaming UI Feb 22 14:29:13 raspberry volumio[1096]: info: Getting Tidal Cloud Configuration Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:29:13 raspberry volumio[1096]: info: Getting Qobuz Cloud Configuration Feb 22 14:29:13 raspberry volumio[1096]: info: Asking plugin for UI Config Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:29:13 raspberry volumio[1096]: info: Getting Spotify Cloud Configuration Feb 22 14:29:13 raspberry volumio[1096]: info: Asking plugin for UI Config Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:29:13 raspberry volumio[1096]: info: Saving Spotify Acccount Feb 22 14:29:13 raspberry volumio[1096]: info: Got Tidal Cloud Configuration Feb 22 14:29:13 raspberry volumio[1096]: info: Got it Feb 22 14:29:13 raspberry volumio[1096]: info: Got it Feb 22 14:29:13 raspberry volumio[1096]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 14:29:13 raspberry volumio[1096]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:13 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:13 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 22 14:29:14 raspberry go-librespot[5450]: time="2025-02-22T14:29:14+01:00" level=trace msg="sent dealer ping" Feb 22 14:29:14 raspberry go-librespot[5450]: time="2025-02-22T14:29:14+01:00" level=trace msg="received dealer pong" Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:14 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:14 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 376. Feb 22 14:29:14 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:14 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:14 raspberry volumio[5564]: Traceback (most recent call last): Feb 22 14:29:14 raspberry volumio[5564]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:14 raspberry volumio[5564]: from aiohttp import web Feb 22 14:29:14 raspberry volumio[5564]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:14 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:14 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:14 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:14 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:15 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:15 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:16 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:16 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 377. Feb 22 14:29:16 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:16 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:16 raspberry volumio[5565]: Traceback (most recent call last): Feb 22 14:29:16 raspberry volumio[5565]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:16 raspberry volumio[5565]: from aiohttp import web Feb 22 14:29:16 raspberry volumio[5565]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:16 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:16 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:16 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:16 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:17 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:17 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:18 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:18 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 378. Feb 22 14:29:18 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:18 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:18 raspberry volumio[5580]: Traceback (most recent call last): Feb 22 14:29:18 raspberry volumio[5580]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:18 raspberry volumio[5580]: from aiohttp import web Feb 22 14:29:18 raspberry volumio[5580]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:18 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:18 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:18 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:18 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:19 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:19 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:20 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:20 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:20 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 379. Feb 22 14:29:21 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:21 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:21 raspberry volumio[5581]: Traceback (most recent call last): Feb 22 14:29:21 raspberry volumio[5581]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:21 raspberry volumio[5581]: from aiohttp import web Feb 22 14:29:21 raspberry volumio[5581]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:21 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:21 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:21 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:21 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: Disabling MyMusic plugin upnp Feb 22 14:29:22 raspberry sudo[5583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 22 14:29:22 raspberry sudo[5583]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 14:29:22 raspberry volumio[1096]: error: Upnp client error: Error: This socket has been ended by the other party Feb 22 14:29:22 raspberry systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:22 raspberry volumio[1096]: info: CoreCommandRouter::volumioGetState Feb 22 14:29:22 raspberry volumio[1096]: info: CorePlayQueue::getTrack 3 Feb 22 14:29:23 raspberry volumio[1096]: info: Enabling MyMusic plugin upnp Feb 22 14:29:23 raspberry volumio[1096]: info: Enabling plugin upnp Feb 22 14:29:23 raspberry volumio[1096]: info: Loading plugin "upnp"... Feb 22 14:29:23 raspberry volumio[1096]: info: [1740230963223] Starting Upmpd Daemon Feb 22 14:29:23 raspberry volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 14:29:23 raspberry volumio[1096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 14:29:23 raspberry volumio[1096]: Error: listen EADDRINUSE: address already in use :::6599 Feb 22 14:29:23 raspberry volumio[1096]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Feb 22 14:29:23 raspberry volumio[1096]: at listenInCluster (net.js:1366:12) Feb 22 14:29:23 raspberry volumio[1096]: at Server.listen (net.js:1452:7) Feb 22 14:29:23 raspberry volumio[1096]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 22 14:29:23 raspberry volumio[1096]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 22 14:29:23 raspberry volumio[1096]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 22 14:29:23 raspberry volumio[1096]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 22 14:29:23 raspberry volumio[1096]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Feb 22 14:29:23 raspberry volumio[1096]: code: 'EADDRINUSE', Feb 22 14:29:23 raspberry volumio[1096]: errno: -98, Feb 22 14:29:23 raspberry volumio[1096]: syscall: 'listen', Feb 22 14:29:23 raspberry volumio[1096]: address: '::', Feb 22 14:29:23 raspberry volumio[1096]: port: 6599 Feb 22 14:29:23 raspberry volumio[1096]: } Feb 22 14:29:23 raspberry volumio[1096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 14:29:23 raspberry systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 22 14:29:23 raspberry systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 380. Feb 22 14:29:23 raspberry systemd[1]: Stopped FusionDsp Daemon. Feb 22 14:29:23 raspberry systemd[1]: Started FusionDsp Daemon. Feb 22 14:29:23 raspberry volumio[5589]: Traceback (most recent call last): Feb 22 14:29:23 raspberry volumio[5589]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 22 14:29:23 raspberry volumio[5589]: from aiohttp import web Feb 22 14:29:23 raspberry volumio[5589]: ModuleNotFoundError: No module named 'aiohttp' Feb 22 14:29:23 raspberry systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 22 14:29:23 raspberry systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 22 14:29:23 raspberry sudo[5598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-22 14:28 Feb 22 14:29:23 raspberry sudo[5598]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"