-- Logs begin at Thu 2019-02-14 15:11:58 +05, end at Mon 2025-02-24 07:02:53 +05. -- Feb 24 07:01:00 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 674. Feb 24 07:01:00 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:00 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:01 volumio volumio[9463]: Traceback (most recent call last): Feb 24 07:01:01 volumio volumio[9463]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:01 volumio volumio[9463]: from aiohttp import web Feb 24 07:01:01 volumio volumio[9463]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:01 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:01 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 675. Feb 24 07:01:03 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:03 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:03 volumio volumio[9481]: Traceback (most recent call last): Feb 24 07:01:03 volumio volumio[9481]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:03 volumio volumio[9481]: from aiohttp import web Feb 24 07:01:03 volumio volumio[9481]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 676. Feb 24 07:01:05 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:05 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:05 volumio volumio[9482]: Traceback (most recent call last): Feb 24 07:01:05 volumio volumio[9482]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:05 volumio volumio[9482]: from aiohttp import web Feb 24 07:01:05 volumio volumio[9482]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:07 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 677. Feb 24 07:01:07 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:07 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:07 volumio volumio[9483]: Traceback (most recent call last): Feb 24 07:01:07 volumio volumio[9483]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:07 volumio volumio[9483]: from aiohttp import web Feb 24 07:01:07 volumio volumio[9483]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:07 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:07 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:09 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 678. Feb 24 07:01:09 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:09 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:10 volumio volumio[9525]: Traceback (most recent call last): Feb 24 07:01:10 volumio volumio[9525]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:10 volumio volumio[9525]: from aiohttp import web Feb 24 07:01:10 volumio volumio[9525]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 679. Feb 24 07:01:12 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:12 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:12 volumio volumio[9543]: Traceback (most recent call last): Feb 24 07:01:12 volumio volumio[9543]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:12 volumio volumio[9543]: from aiohttp import web Feb 24 07:01:12 volumio volumio[9543]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:14 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 680. Feb 24 07:01:14 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:14 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:14 volumio volumio[9544]: Traceback (most recent call last): Feb 24 07:01:14 volumio volumio[9544]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:14 volumio volumio[9544]: from aiohttp import web Feb 24 07:01:14 volumio volumio[9544]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 681. Feb 24 07:01:16 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:16 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:16 volumio volumio[9547]: Traceback (most recent call last): Feb 24 07:01:16 volumio volumio[9547]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:16 volumio volumio[9547]: from aiohttp import web Feb 24 07:01:16 volumio volumio[9547]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 24 07:01:17 volumio volumio[978]: info: In handleBrowseUri, curUri=spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:18 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 682. Feb 24 07:01:18 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:18 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:19 volumio volumio[9548]: Traceback (most recent call last): Feb 24 07:01:19 volumio volumio[9548]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:19 volumio volumio[9548]: from aiohttp import web Feb 24 07:01:19 volumio volumio[9548]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 683. Feb 24 07:01:21 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:21 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:21 volumio volumio[9549]: Traceback (most recent call last): Feb 24 07:01:21 volumio volumio[9549]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:21 volumio volumio[9549]: from aiohttp import web Feb 24 07:01:21 volumio volumio[9549]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:23 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:1P7nfeghjVblSYlL0dJGfy Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:52duxjfgzegRYYwgncbOiz Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:3C1BnrjwkJwd9jJiqJi9Dk Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:6R6uzeKGL5nSH1sZPajy5T Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:5oZvIXJYw3iUexInruxPYr Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:3wCWyMO3HcVoDBbhySlWwq Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:5dyrDD9ySET3N0QkKHOSJn Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:4D5e0uxx9RqvmyoijmBDMY Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:2EgNe1sTTYBkdsripqVfgi Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:1P1J4fyqSCVE3w3or39AAC Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:3ZvhLHcmNZUsgjjsNSHlbW Feb 24 07:01:23 volumio volumio[978]: info: Preloading song: spotify:track:33L47Trap9gvPuOXOTA8nB Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:1P7nfeghjVblSYlL0dJGfy in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:1P7nfeghjVblSYlL0dJGfy Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:52duxjfgzegRYYwgncbOiz in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:52duxjfgzegRYYwgncbOiz Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:3C1BnrjwkJwd9jJiqJi9Dk in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:3C1BnrjwkJwd9jJiqJi9Dk Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:6R6uzeKGL5nSH1sZPajy5T in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:6R6uzeKGL5nSH1sZPajy5T Feb 24 07:01:23 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 684. Feb 24 07:01:23 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:5oZvIXJYw3iUexInruxPYr in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:5oZvIXJYw3iUexInruxPYr Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio volumio[978]: info: No valid Plugin REST Endpoint Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:3wCWyMO3HcVoDBbhySlWwq in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:3wCWyMO3HcVoDBbhySlWwq Feb 24 07:01:23 volumio volumio[9567]: Traceback (most recent call last): Feb 24 07:01:23 volumio volumio[9567]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:23 volumio volumio[9567]: from aiohttp import web Feb 24 07:01:23 volumio volumio[9567]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:23 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:23 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:5dyrDD9ySET3N0QkKHOSJn in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:5dyrDD9ySET3N0QkKHOSJn Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:4D5e0uxx9RqvmyoijmBDMY in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:4D5e0uxx9RqvmyoijmBDMY Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1P7nfeghjVblSYlL0dJGfy","service":"spop","name":"Get Up Get Movin'","artist":"Kisa","album":"The Best Of Italo Volume 1","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:2EgNe1sTTYBkdsripqVfgi in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:2EgNe1sTTYBkdsripqVfgi Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:52duxjfgzegRYYwgncbOiz","service":"spop","name":"Lucky Day","artist":"Star Factory","album":"The Best Of Italo Volume 1","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:1P1J4fyqSCVE3w3or39AAC in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:1P1J4fyqSCVE3w3or39AAC Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3C1BnrjwkJwd9jJiqJi9Dk","service":"spop","name":"Dancing The Whole Day Long","artist":"Hennie Dolsma","album":"The Best Of Italo Volume 1","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:3ZvhLHcmNZUsgjjsNSHlbW in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:3ZvhLHcmNZUsgjjsNSHlbW Feb 24 07:01:23 volumio volumio[978]: info: Exploding uri spotify:track:33L47Trap9gvPuOXOTA8nB in service spop Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:33L47Trap9gvPuOXOTA8nB Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5oZvIXJYw3iUexInruxPYr","service":"spop","name":"Fly Away","artist":"Sushi","album":"The Best Of Italo Volume 1","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:23 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3wCWyMO3HcVoDBbhySlWwq","service":"spop","name":"Go Go Yellow Screen","artist":"Digital Emotion","album":"The Best Of Italo Volume 1","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:24 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6R6uzeKGL5nSH1sZPajy5T","service":"spop","name":"Tonight","artist":"Dianas","album":"The Best Of Italo Volume 1","type":"song","duration":257,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:24 volumio go-librespot[1324]: time="2025-02-24T07:01:24+05:00" level=trace msg="sent dealer ping" Feb 24 07:01:24 volumio go-librespot[1324]: time="2025-02-24T07:01:24+05:00" level=trace msg="received dealer pong" Feb 24 07:01:25 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:25 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 685. Feb 24 07:01:25 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:25 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:25 volumio volumio[9568]: Traceback (most recent call last): Feb 24 07:01:25 volumio volumio[9568]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:25 volumio volumio[9568]: from aiohttp import web Feb 24 07:01:25 volumio volumio[9568]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:25 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:25 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:26 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:26 volumio volumio[978]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 07:01:26 volumio volumio[978]: info: CoreStateMachine::ClearQueue Feb 24 07:01:26 volumio volumio[978]: info: CoreStateMachine::stop Feb 24 07:01:26 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:26 volumio volumio[978]: info: CorePlayQueue::clearPlayQueue Feb 24 07:01:26 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:26 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:26 volumio volumio[978]: info: CoreStateMachine::addQueueItems Feb 24 07:01:26 volumio volumio[978]: info: CorePlayQueue::addQueueItems Feb 24 07:01:26 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:26 volumio volumio[978]: info: Adding Item to queue: spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:26 volumio volumio[978]: info: Exploding uri spotify:album:7DNCZCyjHH6YSedhNRusps in service spop Feb 24 07:01:26 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:27 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:27 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 686. Feb 24 07:01:27 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:27 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:28 volumio volumio[9569]: Traceback (most recent call last): Feb 24 07:01:28 volumio volumio[9569]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:28 volumio volumio[9569]: from aiohttp import web Feb 24 07:01:28 volumio volumio[9569]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:28 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EgNe1sTTYBkdsripqVfgi","service":"spop","name":"Knights On Fire","artist":"The London Knights","album":"The Best Of Italo Volume 1","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:28 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:33L47Trap9gvPuOXOTA8nB","service":"spop","name":"Chant For You","artist":"Gazuzu","album":"The Best Of Italo Volume 1","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:28 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4D5e0uxx9RqvmyoijmBDMY","service":"spop","name":"I Need Your Love Tonight","artist":"Digital Emotion","album":"The Best Of Italo Volume 1","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:28 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5dyrDD9ySET3N0QkKHOSJn","service":"spop","name":"Everybody Loves The Sunshine","artist":"Fantastique","album":"The Best Of Italo Volume 1","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:28 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ZvhLHcmNZUsgjjsNSHlbW","service":"spop","name":"Hey Hey Let's Dance Tonight","artist":"DJ kat","album":"The Best Of Italo Volume 1","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:28 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1P1J4fyqSCVE3w3or39AAC","service":"spop","name":"Aruba","artist":"Gazuzu","album":"The Best Of Italo Volume 1","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b27329ef5170480d2c4378dc94a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 07:01:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:28 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Feb 24 07:01:28 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Feb 24 07:01:28 volumio volumio[978]: info: CoreCommandRouter::volumioPlay Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::play index 0 Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::stop Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::play index undefined Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:28 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Feb 24 07:01:28 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Feb 24 07:01:28 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Feb 24 07:01:28 volumio volumio[978]: info: [1740362488954] ControllerSpotify::clearAddPlayTrack Feb 24 07:01:28 volumio volumio[978]: info: Sending Spotify command with payload to local API: /player/play Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=debug msg="resolved context of track" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=trace msg="emitting websocket event: will_play" Feb 24 07:01:29 volumio volumio[978]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1P7nfeghjVblSYlL0dJGfy","play_origin":"go-librespot"}} Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=debug msg="selected format OGG_VORBIS_320 (e3b31c2538418c840cd8f9c324cddabf1c61e43d)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=debug msg="requested aes key for file e3b31c2538418c840cd8f9c324cddabf1c61e43d, gid: 1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:29 volumio go-librespot[1324]: time="2025-02-24T07:01:29+05:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1P7nfeghjVblSYlL0dJGfy: failed retrieving audio key: failed retrieving aes key with code 1" Feb 24 07:01:29 volumio volumio[978]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 24 07:01:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 687. Feb 24 07:01:30 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:30 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:30 volumio volumio[9570]: Traceback (most recent call last): Feb 24 07:01:30 volumio volumio[9570]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:30 volumio volumio[9570]: from aiohttp import web Feb 24 07:01:30 volumio volumio[9570]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:32 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 688. Feb 24 07:01:32 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:32 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:32 volumio volumio[9588]: Traceback (most recent call last): Feb 24 07:01:32 volumio volumio[9588]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:32 volumio volumio[9588]: from aiohttp import web Feb 24 07:01:32 volumio volumio[9588]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:32 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:32 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:34 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:34 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 689. Feb 24 07:01:34 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:34 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:34 volumio volumio[9589]: Traceback (most recent call last): Feb 24 07:01:34 volumio volumio[9589]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:34 volumio volumio[9589]: from aiohttp import web Feb 24 07:01:34 volumio volumio[9589]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:36 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 690. Feb 24 07:01:36 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:36 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:37 volumio volumio[9591]: Traceback (most recent call last): Feb 24 07:01:37 volumio volumio[9591]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:37 volumio volumio[9591]: from aiohttp import web Feb 24 07:01:37 volumio volumio[9591]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 691. Feb 24 07:01:39 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:39 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:39 volumio volumio[9592]: Traceback (most recent call last): Feb 24 07:01:39 volumio volumio[9592]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:39 volumio volumio[9592]: from aiohttp import web Feb 24 07:01:39 volumio volumio[9592]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:39 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:39 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:41 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:41 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 692. Feb 24 07:01:41 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:41 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:41 volumio volumio[9593]: Traceback (most recent call last): Feb 24 07:01:41 volumio volumio[9593]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:41 volumio volumio[9593]: from aiohttp import web Feb 24 07:01:41 volumio volumio[9593]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:41 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:41 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:43 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:43 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 693. Feb 24 07:01:43 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:43 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:43 volumio volumio[9611]: Traceback (most recent call last): Feb 24 07:01:43 volumio volumio[9611]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:43 volumio volumio[9611]: from aiohttp import web Feb 24 07:01:43 volumio volumio[9611]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:43 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:43 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:45 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 694. Feb 24 07:01:45 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:45 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:46 volumio volumio[9612]: Traceback (most recent call last): Feb 24 07:01:46 volumio volumio[9612]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:46 volumio volumio[9612]: from aiohttp import web Feb 24 07:01:46 volumio volumio[9612]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 695. Feb 24 07:01:48 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:48 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:48 volumio volumio[9613]: Traceback (most recent call last): Feb 24 07:01:48 volumio volumio[9613]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:48 volumio volumio[9613]: from aiohttp import web Feb 24 07:01:48 volumio volumio[9613]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=trace msg="received accesspoint ping" Feb 24 07:01:49 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:49 volumio volumio[978]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::ClearQueue Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::stop Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::clearPlayQueue Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:49 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::addQueueItems Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::addQueueItems Feb 24 07:01:49 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:1P7nfeghjVblSYlL0dJGfy Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:1P7nfeghjVblSYlL0dJGfy Feb 24 07:01:49 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Feb 24 07:01:49 volumio volumio[978]: info: CoreCommandRouter::volumioPlay Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::play index 0 Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::addQueueItems Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::addQueueItems Feb 24 07:01:49 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:52duxjfgzegRYYwgncbOiz Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:52duxjfgzegRYYwgncbOiz Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:3C1BnrjwkJwd9jJiqJi9Dk Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:3C1BnrjwkJwd9jJiqJi9Dk Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:6R6uzeKGL5nSH1sZPajy5T Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:6R6uzeKGL5nSH1sZPajy5T Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:5oZvIXJYw3iUexInruxPYr Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:5oZvIXJYw3iUexInruxPYr Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:3wCWyMO3HcVoDBbhySlWwq Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:3wCWyMO3HcVoDBbhySlWwq Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:5dyrDD9ySET3N0QkKHOSJn Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:5dyrDD9ySET3N0QkKHOSJn Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:4D5e0uxx9RqvmyoijmBDMY Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:4D5e0uxx9RqvmyoijmBDMY Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:2EgNe1sTTYBkdsripqVfgi Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:2EgNe1sTTYBkdsripqVfgi Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:1P1J4fyqSCVE3w3or39AAC Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:1P1J4fyqSCVE3w3or39AAC Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:3ZvhLHcmNZUsgjjsNSHlbW Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:3ZvhLHcmNZUsgjjsNSHlbW Feb 24 07:01:49 volumio volumio[978]: info: Adding Item to queue: spotify:track:33L47Trap9gvPuOXOTA8nB Feb 24 07:01:49 volumio volumio[978]: info: Using cached record of: spotify:track:33L47Trap9gvPuOXOTA8nB Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::stop Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:49 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::play index undefined Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Feb 24 07:01:49 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Feb 24 07:01:49 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Feb 24 07:01:49 volumio volumio[978]: info: [1740362509323] ControllerSpotify::clearAddPlayTrack Feb 24 07:01:49 volumio volumio[978]: info: Sending Spotify command with payload to local API: /player/play Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=trace msg="received accesspoint pong ack" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=debug msg="resolved context of track" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=trace msg="emitting websocket event: will_play" Feb 24 07:01:49 volumio volumio[978]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1P7nfeghjVblSYlL0dJGfy","play_origin":"go-librespot"}} Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=debug msg="selected format OGG_VORBIS_320 (e3b31c2538418c840cd8f9c324cddabf1c61e43d)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=debug msg="requested aes key for file e3b31c2538418c840cd8f9c324cddabf1c61e43d, gid: 1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:49 volumio go-librespot[1324]: time="2025-02-24T07:01:49+05:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1P7nfeghjVblSYlL0dJGfy: failed retrieving audio key: failed retrieving aes key with code 1" Feb 24 07:01:49 volumio volumio[978]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 24 07:01:50 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 696. Feb 24 07:01:50 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:50 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:50 volumio volumio[9614]: Traceback (most recent call last): Feb 24 07:01:50 volumio volumio[9614]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:50 volumio volumio[9614]: from aiohttp import web Feb 24 07:01:50 volumio volumio[9614]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:52 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:52 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 697. Feb 24 07:01:52 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:52 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:52 volumio volumio[9632]: Traceback (most recent call last): Feb 24 07:01:52 volumio volumio[9632]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:52 volumio volumio[9632]: from aiohttp import web Feb 24 07:01:52 volumio volumio[9632]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:52 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:52 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:52 volumio volumio[978]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::ClearQueue Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::stop Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::clearPlayQueue Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:52 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:52 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::addQueueItems Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::addQueueItems Feb 24 07:01:52 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:52 volumio volumio[978]: info: Adding Item to queue: spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:52 volumio volumio[978]: info: Using cached record of: spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:52 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Feb 24 07:01:52 volumio volumio[978]: info: CoreCommandRouter::volumioPlay Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::play index 0 Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::stop Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::play index undefined Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Feb 24 07:01:52 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Feb 24 07:01:52 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Feb 24 07:01:52 volumio volumio[978]: info: [1740362512785] ControllerSpotify::clearAddPlayTrack Feb 24 07:01:52 volumio volumio[978]: info: Sending Spotify command with payload to local API: /player/play Feb 24 07:01:52 volumio go-librespot[1324]: time="2025-02-24T07:01:52+05:00" level=debug msg="resolved context of track" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:52 volumio go-librespot[1324]: time="2025-02-24T07:01:52+05:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:52 volumio go-librespot[1324]: time="2025-02-24T07:01:52+05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:52 volumio go-librespot[1324]: time="2025-02-24T07:01:52+05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 07:01:52 volumio go-librespot[1324]: time="2025-02-24T07:01:52+05:00" level=trace msg="emitting websocket event: will_play" Feb 24 07:01:52 volumio volumio[978]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1P7nfeghjVblSYlL0dJGfy","play_origin":"go-librespot"}} Feb 24 07:01:53 volumio go-librespot[1324]: time="2025-02-24T07:01:53+05:00" level=debug msg="selected format OGG_VORBIS_320 (e3b31c2538418c840cd8f9c324cddabf1c61e43d)" uri="spotify:track:1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:53 volumio go-librespot[1324]: time="2025-02-24T07:01:53+05:00" level=debug msg="requested aes key for file e3b31c2538418c840cd8f9c324cddabf1c61e43d, gid: 1P7nfeghjVblSYlL0dJGfy" Feb 24 07:01:53 volumio go-librespot[1324]: time="2025-02-24T07:01:53+05:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1P7nfeghjVblSYlL0dJGfy: failed retrieving audio key: failed retrieving aes key with code 1" Feb 24 07:01:53 volumio volumio[978]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 24 07:01:53 volumio volumio[978]: info: CoreCommandRouter::volumioplayNextItems Feb 24 07:01:53 volumio volumio[978]: info: CoreStateMachine::playNextItems Feb 24 07:01:53 volumio volumio[978]: info: CorePlayQueue::addPlayNextItems Feb 24 07:01:53 volumio volumio[978]: info: Preload queue cleared Feb 24 07:01:53 volumio volumio[978]: info: Adding Item to play next: spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:53 volumio volumio[978]: info: Using cached record of: spotify:album:7DNCZCyjHH6YSedhNRusps Feb 24 07:01:53 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Feb 24 07:01:53 volumio volumio[978]: info: CorePlayQueue::saveQueue Feb 24 07:01:53 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Feb 24 07:01:53 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Feb 24 07:01:54 volumio go-librespot[1324]: time="2025-02-24T07:01:54+05:00" level=trace msg="sent dealer ping" Feb 24 07:01:54 volumio go-librespot[1324]: time="2025-02-24T07:01:54+05:00" level=trace msg="received dealer pong" Feb 24 07:01:54 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:54 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 698. Feb 24 07:01:54 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:54 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:55 volumio volumio[9633]: Traceback (most recent call last): Feb 24 07:01:55 volumio volumio[9633]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:55 volumio volumio[9633]: from aiohttp import web Feb 24 07:01:55 volumio volumio[9633]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:57 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 699. Feb 24 07:01:57 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:57 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:57 volumio volumio[9635]: Traceback (most recent call last): Feb 24 07:01:57 volumio volumio[9635]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:57 volumio volumio[9635]: from aiohttp import web Feb 24 07:01:57 volumio volumio[9635]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:01:59 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:01:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 700. Feb 24 07:01:59 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:01:59 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:01:59 volumio volumio[9636]: Traceback (most recent call last): Feb 24 07:01:59 volumio volumio[9636]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:01:59 volumio volumio[9636]: from aiohttp import web Feb 24 07:01:59 volumio volumio[9636]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:01:59 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:01:59 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 701. Feb 24 07:02:01 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:01 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:01 volumio volumio[9637]: Traceback (most recent call last): Feb 24 07:02:01 volumio volumio[9637]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:01 volumio volumio[9637]: from aiohttp import web Feb 24 07:02:01 volumio volumio[9637]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 702. Feb 24 07:02:03 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:03 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:04 volumio volumio[9655]: Traceback (most recent call last): Feb 24 07:02:04 volumio volumio[9655]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:04 volumio volumio[9655]: from aiohttp import web Feb 24 07:02:04 volumio volumio[9655]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:05 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 07:02:05 volumio volumio[978]: info: Preload queue cleared Feb 24 07:02:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 703. Feb 24 07:02:06 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:06 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:06 volumio volumio[9656]: Traceback (most recent call last): Feb 24 07:02:06 volumio volumio[9656]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:06 volumio volumio[9656]: from aiohttp import web Feb 24 07:02:06 volumio volumio[9656]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 704. Feb 24 07:02:08 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:08 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:08 volumio volumio[9657]: Traceback (most recent call last): Feb 24 07:02:08 volumio volumio[9657]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:08 volumio volumio[9657]: from aiohttp import web Feb 24 07:02:08 volumio volumio[9657]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:09 volumio volumio[978]: Searching plugin music_service/webradio Feb 24 07:02:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 24 07:02:10 volumio volumio[978]: (node:978) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Feb 24 07:02:10 volumio volumio[978]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Feb 24 07:02:10 volumio volumio[978]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Feb 24 07:02:10 volumio volumio[978]: at runMicrotasks () Feb 24 07:02:10 volumio volumio[978]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Feb 24 07:02:10 volumio volumio[978]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 24 07:02:10 volumio volumio[978]: (node:978) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Feb 24 07:02:10 volumio volumio[978]: (node:978) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Feb 24 07:02:10 volumio volumio[978]: Searching plugin music_service/webradio Feb 24 07:02:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 24 07:02:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 705. Feb 24 07:02:10 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:10 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:10 volumio volumio[9699]: Traceback (most recent call last): Feb 24 07:02:10 volumio volumio[9699]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:10 volumio volumio[9699]: from aiohttp import web Feb 24 07:02:10 volumio volumio[9699]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:10 volumio volumio[978]: info: All search sources collected, pushing search results Feb 24 07:02:12 volumio volumio[978]: Searching plugin music_service/webradio Feb 24 07:02:12 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 24 07:02:12 volumio volumio[978]: info: All search sources collected, pushing search results Feb 24 07:02:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 706. Feb 24 07:02:12 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:12 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:13 volumio volumio[9717]: Traceback (most recent call last): Feb 24 07:02:13 volumio volumio[9717]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:13 volumio volumio[9717]: from aiohttp import web Feb 24 07:02:13 volumio volumio[9717]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:14 volumio volumio[978]: Searching plugin music_service/webradio Feb 24 07:02:14 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 24 07:02:14 volumio volumio[978]: info: All search sources collected, pushing search results Feb 24 07:02:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 707. Feb 24 07:02:15 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:15 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:15 volumio volumio[9718]: Traceback (most recent call last): Feb 24 07:02:15 volumio volumio[9718]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:15 volumio volumio[9718]: from aiohttp import web Feb 24 07:02:15 volumio volumio[9718]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:15 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:15 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 708. Feb 24 07:02:17 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:17 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:17 volumio volumio[9720]: Traceback (most recent call last): Feb 24 07:02:17 volumio volumio[9720]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:17 volumio volumio[9720]: from aiohttp import web Feb 24 07:02:17 volumio volumio[9720]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:18 volumio volumio[978]: Searching plugin music_service/webradio Feb 24 07:02:18 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 24 07:02:18 volumio volumio[978]: info: All search sources collected, pushing search results Feb 24 07:02:18 volumio volumio[978]: Searching plugin music_service/webradio Feb 24 07:02:18 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 24 07:02:19 volumio volumio[978]: info: All search sources collected, pushing search results Feb 24 07:02:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 709. Feb 24 07:02:19 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:19 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:19 volumio volumio[9721]: Traceback (most recent call last): Feb 24 07:02:19 volumio volumio[9721]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:19 volumio volumio[9721]: from aiohttp import web Feb 24 07:02:19 volumio volumio[9721]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 710. Feb 24 07:02:21 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:22 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:22 volumio volumio[9736]: Traceback (most recent call last): Feb 24 07:02:22 volumio volumio[9736]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:22 volumio volumio[9736]: from aiohttp import web Feb 24 07:02:22 volumio volumio[9736]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:22 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:22 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:24 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 711. Feb 24 07:02:24 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:24 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:24 volumio volumio[9740]: Traceback (most recent call last): Feb 24 07:02:24 volumio volumio[9740]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:24 volumio volumio[9740]: from aiohttp import web Feb 24 07:02:24 volumio volumio[9740]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:24 volumio go-librespot[1324]: time="2025-02-24T07:02:24+05:00" level=trace msg="sent dealer ping" Feb 24 07:02:24 volumio go-librespot[1324]: time="2025-02-24T07:02:24+05:00" level=trace msg="received dealer pong" Feb 24 07:02:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 712. Feb 24 07:02:26 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:26 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:26 volumio volumio[9741]: Traceback (most recent call last): Feb 24 07:02:26 volumio volumio[9741]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:26 volumio volumio[9741]: from aiohttp import web Feb 24 07:02:26 volumio volumio[9741]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:26 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:26 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:28 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 713. Feb 24 07:02:28 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:28 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:28 volumio volumio[9742]: Traceback (most recent call last): Feb 24 07:02:28 volumio volumio[9742]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:28 volumio volumio[9742]: from aiohttp import web Feb 24 07:02:28 volumio volumio[9742]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 714. Feb 24 07:02:30 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:30 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:31 volumio volumio[9743]: Traceback (most recent call last): Feb 24 07:02:31 volumio volumio[9743]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:31 volumio volumio[9743]: from aiohttp import web Feb 24 07:02:31 volumio volumio[9743]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:31 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:31 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 715. Feb 24 07:02:33 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:33 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:33 volumio volumio[9761]: Traceback (most recent call last): Feb 24 07:02:33 volumio volumio[9761]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:33 volumio volumio[9761]: from aiohttp import web Feb 24 07:02:33 volumio volumio[9761]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:35 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:35 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 716. Feb 24 07:02:35 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:35 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:35 volumio volumio[9762]: Traceback (most recent call last): Feb 24 07:02:35 volumio volumio[9762]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:35 volumio volumio[9762]: from aiohttp import web Feb 24 07:02:35 volumio volumio[9762]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:35 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:35 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:37 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:37 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 717. Feb 24 07:02:37 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:37 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:37 volumio volumio[9764]: Traceback (most recent call last): Feb 24 07:02:37 volumio volumio[9764]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:37 volumio volumio[9764]: from aiohttp import web Feb 24 07:02:37 volumio volumio[9764]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 718. Feb 24 07:02:39 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:39 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:40 volumio volumio[9765]: Traceback (most recent call last): Feb 24 07:02:40 volumio volumio[9765]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:40 volumio volumio[9765]: from aiohttp import web Feb 24 07:02:40 volumio volumio[9765]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:40 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:40 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:42 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 719. Feb 24 07:02:42 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:42 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:42 volumio volumio[9780]: Traceback (most recent call last): Feb 24 07:02:42 volumio volumio[9780]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:42 volumio volumio[9780]: from aiohttp import web Feb 24 07:02:42 volumio volumio[9780]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:42 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:42 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:44 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:44 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 720. Feb 24 07:02:44 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:44 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:44 volumio volumio[9784]: Traceback (most recent call last): Feb 24 07:02:44 volumio volumio[9784]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:44 volumio volumio[9784]: from aiohttp import web Feb 24 07:02:44 volumio volumio[9784]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:44 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:44 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:46 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:46 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 721. Feb 24 07:02:46 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:46 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:46 volumio volumio[9785]: Traceback (most recent call last): Feb 24 07:02:46 volumio volumio[9785]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:46 volumio volumio[9785]: from aiohttp import web Feb 24 07:02:46 volumio volumio[9785]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:48 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 24 07:02:48 volumio volumio[978]: info: Preload queue cleared Feb 24 07:02:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 722. Feb 24 07:02:48 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:48 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:49 volumio volumio[9786]: Traceback (most recent call last): Feb 24 07:02:49 volumio volumio[9786]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:49 volumio volumio[9786]: from aiohttp import web Feb 24 07:02:49 volumio volumio[9786]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:49 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:49 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:50 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 24 07:02:50 volumio volumio[978]: info: Preload queue cleared Feb 24 07:02:51 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:51 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 723. Feb 24 07:02:51 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:51 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:51 volumio volumio[9787]: Traceback (most recent call last): Feb 24 07:02:51 volumio volumio[9787]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:51 volumio volumio[9787]: from aiohttp import web Feb 24 07:02:51 volumio volumio[9787]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:51 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:51 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:51 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 24 07:02:51 volumio volumio[978]: info: Preload queue cleared Feb 24 07:02:53 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 24 07:02:53 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 07:02:53 volumio volumio[978]: TypeError: Cannot read property 'length' of undefined Feb 24 07:02:53 volumio volumio[978]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 24 07:02:53 volumio volumio[978]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 24 07:02:53 volumio volumio[978]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 24 07:02:53 volumio volumio[978]: at Parser.emit (events.js:315:20) Feb 24 07:02:53 volumio volumio[978]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 24 07:02:53 volumio volumio[978]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 24 07:02:53 volumio volumio[978]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 24 07:02:53 volumio volumio[978]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 24 07:02:53 volumio volumio[978]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 24 07:02:53 volumio volumio[978]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 24 07:02:53 volumio volumio[978]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 24 07:02:53 volumio volumio[978]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 24 07:02:53 volumio volumio[978]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 24 07:02:53 volumio volumio[978]: at IncomingMessage.emit (events.js:327:22) Feb 24 07:02:53 volumio volumio[978]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 24 07:02:53 volumio volumio[978]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 24 07:02:53 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 07:02:53 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Feb 24 07:02:53 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 724. Feb 24 07:02:53 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 24 07:02:53 volumio systemd[1]: Started FusionDsp Daemon. Feb 24 07:02:53 volumio volumio[9812]: Traceback (most recent call last): Feb 24 07:02:53 volumio volumio[9812]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Feb 24 07:02:53 volumio volumio[9812]: from aiohttp import web Feb 24 07:02:53 volumio volumio[9812]: ModuleNotFoundError: No module named 'aiohttp' Feb 24 07:02:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Feb 24 07:02:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 24 07:02:53 volumio sudo[9815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 07:01 Feb 24 07:02:53 volumio sudo[9815]: 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"