-- Logs begin at Tue 2024-09-17 17:06:55 CEST, end at Thu 2024-10-17 19:08:28 CEST. -- Oct 17 19:07:00 volumio321 volumio[4839]: Traceback (most recent call last): Oct 17 19:07:00 volumio321 volumio[4839]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:00 volumio321 volumio[4839]: from aiohttp import web Oct 17 19:07:00 volumio321 volumio[4839]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:00 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:00 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:02 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:02 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1147. Oct 17 19:07:02 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:02 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:03 volumio321 volumio[4840]: Traceback (most recent call last): Oct 17 19:07:03 volumio321 volumio[4840]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:03 volumio321 volumio[4840]: from aiohttp import web Oct 17 19:07:03 volumio321 volumio[4840]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:03 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:03 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:06 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:06 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1148. Oct 17 19:07:06 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:06 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:07 volumio321 volumio[4841]: Traceback (most recent call last): Oct 17 19:07:07 volumio321 volumio[4841]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:07 volumio321 volumio[4841]: from aiohttp import web Oct 17 19:07:07 volumio321 volumio[4841]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:07 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:07 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:09 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:09 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1149. Oct 17 19:07:09 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:09 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:10 volumio321 volumio[4842]: Traceback (most recent call last): Oct 17 19:07:10 volumio321 volumio[4842]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:10 volumio321 volumio[4842]: from aiohttp import web Oct 17 19:07:10 volumio321 volumio[4842]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:10 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:10 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:12 volumio321 go-librespot[1079]: time="2024-10-17T19:07:12+02:00" level=debug msg="fetched chunk 14/14, size: 300844" uri="spotify:track:003A4aWr4PXdUvYG2Vqw9Z" Oct 17 19:07:12 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:12 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1150. Oct 17 19:07:12 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:12 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:13 volumio321 volumio[4843]: Traceback (most recent call last): Oct 17 19:07:13 volumio321 volumio[4843]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:13 volumio321 volumio[4843]: from aiohttp import web Oct 17 19:07:13 volumio321 volumio[4843]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:13 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:13 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:15 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:15 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1151. Oct 17 19:07:15 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:15 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:16 volumio321 volumio[4845]: Traceback (most recent call last): Oct 17 19:07:16 volumio321 volumio[4845]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:16 volumio321 volumio[4845]: from aiohttp import web Oct 17 19:07:16 volumio321 volumio[4845]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:16 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:16 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:18 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:18 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1152. Oct 17 19:07:18 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:18 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:19 volumio321 volumio[4846]: Traceback (most recent call last): Oct 17 19:07:19 volumio321 volumio[4846]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:19 volumio321 volumio[4846]: from aiohttp import web Oct 17 19:07:19 volumio321 volumio[4846]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:19 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:19 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:22 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:22 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1153. Oct 17 19:07:22 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:22 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:23 volumio321 volumio[4873]: Traceback (most recent call last): Oct 17 19:07:23 volumio321 volumio[4873]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:23 volumio321 volumio[4873]: from aiohttp import web Oct 17 19:07:23 volumio321 volumio[4873]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:23 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:23 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:25 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:25 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1154. Oct 17 19:07:25 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:25 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:26 volumio321 volumio[4887]: Traceback (most recent call last): Oct 17 19:07:26 volumio321 volumio[4887]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:26 volumio321 volumio[4887]: from aiohttp import web Oct 17 19:07:26 volumio321 volumio[4887]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:26 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:26 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:27 volumio321 go-librespot[1079]: time="2024-10-17T19:07:27+02:00" level=debug msg="prefetching next track" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:27 volumio321 go-librespot[1079]: time="2024-10-17T19:07:27+02:00" level=debug msg="selected format OGG_VORBIS_320 (552f043dfef5690ab2675c23c1e196c35334c03a)" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:27 volumio321 go-librespot[1079]: time="2024-10-17T19:07:27+02:00" level=debug msg="requested aes key for file 552f043dfef5690ab2675c23c1e196c35334c03a, gid: 029NqmIySn1kOY305AAhxT" Oct 17 19:07:28 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:28 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1155. Oct 17 19:07:28 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:28 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:29 volumio321 go-librespot[1079]: time="2024-10-17T19:07:29+02:00" level=debug msg="fetched first chunk of 26, total size is 13429320 bytes" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:29 volumio321 go-librespot[1079]: time="2024-10-17T19:07:29+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:29 volumio321 go-librespot[1079]: time="2024-10-17T19:07:29+02:00" level=info msg="prefetched track \"Sledgehammer\" (duration: 313533ms)" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:29 volumio321 volumio[4889]: Traceback (most recent call last): Oct 17 19:07:29 volumio321 volumio[4889]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:29 volumio321 volumio[4889]: from aiohttp import web Oct 17 19:07:29 volumio321 volumio[4889]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:30 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:30 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:31 volumio321 go-librespot[1079]: time="2024-10-17T19:07:31+02:00" level=debug msg="fetched chunk 3/25, size: 524288" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:32 volumio321 go-librespot[1079]: time="2024-10-17T19:07:32+02:00" level=debug msg="fetched chunk 1/25, size: 524288" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:32 volumio321 go-librespot[1079]: time="2024-10-17T19:07:32+02:00" level=debug msg="fetched chunk 2/25, size: 524288" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:32 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:32 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1156. Oct 17 19:07:32 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:32 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:33 volumio321 volumio[4890]: Traceback (most recent call last): Oct 17 19:07:33 volumio321 volumio[4890]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:33 volumio321 volumio[4890]: from aiohttp import web Oct 17 19:07:33 volumio321 volumio[4890]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:33 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:33 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:35 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:35 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1157. Oct 17 19:07:35 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:35 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:36 volumio321 volumio[4892]: Traceback (most recent call last): Oct 17 19:07:36 volumio321 volumio[4892]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:36 volumio321 volumio[4892]: from aiohttp import web Oct 17 19:07:36 volumio321 volumio[4892]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:36 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:36 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:38 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:38 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1158. Oct 17 19:07:38 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:38 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:39 volumio321 volumio[4893]: Traceback (most recent call last): Oct 17 19:07:39 volumio321 volumio[4893]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:39 volumio321 volumio[4893]: from aiohttp import web Oct 17 19:07:39 volumio321 volumio[4893]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:39 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:39 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:42 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:42 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1159. Oct 17 19:07:42 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:42 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:43 volumio321 volumio[4894]: Traceback (most recent call last): Oct 17 19:07:43 volumio321 volumio[4894]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:43 volumio321 volumio[4894]: from aiohttp import web Oct 17 19:07:43 volumio321 volumio[4894]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:43 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:43 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:45 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:45 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1160. Oct 17 19:07:45 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:45 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:46 volumio321 volumio[4895]: Traceback (most recent call last): Oct 17 19:07:46 volumio321 volumio[4895]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:46 volumio321 volumio[4895]: from aiohttp import web Oct 17 19:07:46 volumio321 volumio[4895]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:46 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:46 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:48 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:48 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1161. Oct 17 19:07:48 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:48 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:49 volumio321 volumio[4896]: Traceback (most recent call last): Oct 17 19:07:49 volumio321 volumio[4896]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:49 volumio321 volumio[4896]: from aiohttp import web Oct 17 19:07:49 volumio321 volumio[4896]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:49 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:49 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:51 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:51 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1162. Oct 17 19:07:51 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:51 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:52 volumio321 volumio[4897]: Traceback (most recent call last): Oct 17 19:07:52 volumio321 volumio[4897]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:52 volumio321 volumio[4897]: from aiohttp import web Oct 17 19:07:52 volumio321 volumio[4897]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:52 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:52 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:55 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:55 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1163. Oct 17 19:07:55 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:55 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:55 volumio321 volumio[4898]: Traceback (most recent call last): Oct 17 19:07:55 volumio321 volumio[4898]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:55 volumio321 volumio[4898]: from aiohttp import web Oct 17 19:07:55 volumio321 volumio[4898]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:56 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:56 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:58 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:07:58 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1164. Oct 17 19:07:58 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:07:58 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=trace msg="emitting websocket event: not_playing" Oct 17 19:07:59 volumio321 volumio[818]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:003A4aWr4PXdUvYG2Vqw9Z","play_origin":"com.spotify.service.freetierartist"}} Oct 17 19:07:59 volumio321 volumio[4900]: Traceback (most recent call last): Oct 17 19:07:59 volumio321 volumio[4900]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:07:59 volumio321 volumio[4900]: from aiohttp import web Oct 17 19:07:59 volumio321 volumio[4900]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:07:59 volumio321 volumio[818]: error: Failed to decode event: not_playing Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=error msg="failed fetching next tracks" error="failed moving to next index 5 (page 1): failed fetching page: invalid status code from page at artistplaycontext/v1/page/spotify/artist-top-tracks-extensions/7C4sUpWGlTy7IANjruj02I?exclude_uri=spotify:track:6vZZtivPrtpggT5SSXsYTy,spotify:track:029NqmIySn1kOY305AAhxT,spotify:track:1wyluqXP2ujdTpCfm1E617,spotify:track:2t77hjgJY4sC9DoX5uaVUD,spotify:track:1SXSLvVq87U2DctZDXU5Id: 404" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:59 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:07:59 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=trace msg="emitting websocket event: will_play" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=info msg="loaded track \"Sledgehammer\" (paused: false, position: 1ms, duration: 313533ms, prefetched: true)" uri="spotify:track:029NqmIySn1kOY305AAhxT" Oct 17 19:07:59 volumio321 volumio[818]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:029NqmIySn1kOY305AAhxT","play_origin":"com.spotify.service.freetierartist"}} Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=trace msg="scheduling prefetch in 283s" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=trace msg="emitting websocket event: metadata" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="handling play player command from f332deea3e955a1af38c919e460aa95e39e5f53c" Oct 17 19:07:59 volumio321 volumio[818]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:029NqmIySn1kOY305AAhxT","name":"Sledgehammer","artist_names":["Peter Gabriel"],"album_name":"So (Remastered)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0211bb04f55be562c76d8e8ecf","position":1,"duration":313533,"release_date":"year:1986 month:5 day:19","track_number":2,"disc_number":1}} Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:4qWFx0Oo8nLKHJmLhxn9kC" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=trace msg="fetched new page 0 with 66 items (list: 66)" uri="spotify:playlist:4qWFx0Oo8nLKHJmLhxn9kC" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="loading track (paused: false, position: 12ms)" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=trace msg="emitting websocket event: will_play" Oct 17 19:07:59 volumio321 volumio[818]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7CHi4DtfK4heMlQaudCuHK","play_origin":"playlist/ondemand"}} Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="selected format OGG_VORBIS_320 (3e0c53438dc33bccb0051123d88e3cb586350937)" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:07:59 volumio321 go-librespot[1079]: time="2024-10-17T19:07:59+02:00" level=debug msg="requested aes key for file 3e0c53438dc33bccb0051123d88e3cb586350937, gid: 7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:01 volumio321 go-librespot[1079]: time="2024-10-17T19:08:01+02:00" level=debug msg="fetched first chunk of 13, total size is 6682876 bytes" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:01 volumio321 go-librespot[1079]: time="2024-10-17T19:08:01+02:00" level=trace msg="seek to 12ms (diff: 12ms, samples: 529, bytes: 0)" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:01 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:01 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1165. Oct 17 19:08:01 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:01 volumio321 go-librespot[1079]: time="2024-10-17T19:08:01+02:00" level=info msg="loaded track \"Lose Control\" (paused: false, position: 12ms, duration: 168387ms, prefetched: false)" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:01 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:02 volumio321 go-librespot[1079]: time="2024-10-17T19:08:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:08:02 volumio321 go-librespot[1079]: time="2024-10-17T19:08:02+02:00" level=trace msg="scheduling prefetch in 138s" Oct 17 19:08:02 volumio321 go-librespot[1079]: time="2024-10-17T19:08:02+02:00" level=trace msg="emitting websocket event: metadata" Oct 17 19:08:02 volumio321 go-librespot[1079]: time="2024-10-17T19:08:02+02:00" level=debug msg="sending successful reply for dealer request" Oct 17 19:08:02 volumio321 volumio[818]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7CHi4DtfK4heMlQaudCuHK","name":"Lose Control","artist_names":["MEDUZA","Becky Hill","Goodboys"],"album_name":"Lose Control","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d43c59e52d6a8032a4e27fc4","position":12,"duration":168387,"release_date":"year:2019 month:10 day:11","track_number":1,"disc_number":1}} Oct 17 19:08:02 volumio321 volumio[4902]: Traceback (most recent call last): Oct 17 19:08:02 volumio321 volumio[4902]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:02 volumio321 volumio[4902]: from aiohttp import web Oct 17 19:08:02 volumio321 volumio[4902]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:02 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:02 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:02 volumio321 go-librespot[1079]: time="2024-10-17T19:08:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:08:02 volumio321 go-librespot[1079]: time="2024-10-17T19:08:02+02:00" level=trace msg="emitting websocket event: playing" Oct 17 19:08:03 volumio321 volumio[818]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7CHi4DtfK4heMlQaudCuHK","play_origin":"playlist/ondemand"}} Oct 17 19:08:03 volumio321 volumio[818]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 19:08:03 volumio321 volumio[818]: SPOTIFY: {"status":"play","service":"spop","title":"Lose Control","artist":"MEDUZA, Becky Hill, Goodboys","album":"Lose Control","albumart":"https://i.scdn.co/image/ab67616d00001e02d43c59e52d6a8032a4e27fc4","uri":"spotify:track:7CHi4DtfK4heMlQaudCuHK","trackType":"spotify","seek":12,"duration":168,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 17 19:08:03 volumio321 volumio[818]: info: CoreCommandRouter::servicePushState Oct 17 19:08:03 volumio321 volumio[818]: info: CoreStateMachine::pushState Oct 17 19:08:03 volumio321 volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:08:03 volumio321 volumio[818]: info: CoreCommandRouter::volumioPushState Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=trace msg="emitting websocket event: playing" Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=debug msg="handling set_shuffling_context player command from f332deea3e955a1af38c919e460aa95e39e5f53c" Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=debug msg="shuffled context with seed 5637245348325447686 (len: 66, keep: 26)" Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=trace msg="emitting websocket event: shuffle_context" Oct 17 19:08:03 volumio321 volumio[818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 19:08:03 volumio321 go-librespot[1079]: time="2024-10-17T19:08:03+02:00" level=debug msg="sending successful reply for dealer request" Oct 17 19:08:03 volumio321 volumio[818]: TypeError: Cannot read property 'pushState' of null Oct 17 19:08:03 volumio321 volumio[818]: at /volumio/app/index.js:325:32 Oct 17 19:08:03 volumio321 volumio[818]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 17 19:08:03 volumio321 volumio[818]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 17 19:08:03 volumio321 volumio[818]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:323:13) Oct 17 19:08:03 volumio321 volumio[818]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:530:22) Oct 17 19:08:03 volumio321 volumio[818]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:591:10) Oct 17 19:08:03 volumio321 volumio[818]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:413:28) Oct 17 19:08:03 volumio321 volumio[818]: at ControllerSpotify.pushState (/data/plugins/music_service/spop/index.js:430:31) Oct 17 19:08:03 volumio321 volumio[818]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:320:14) Oct 17 19:08:03 volumio321 volumio[818]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Oct 17 19:08:03 volumio321 volumio[818]: at WebSocket.emit (events.js:315:20) Oct 17 19:08:03 volumio321 volumio[818]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Oct 17 19:08:03 volumio321 volumio[818]: at Receiver.emit (events.js:315:20) Oct 17 19:08:03 volumio321 volumio[818]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Oct 17 19:08:03 volumio321 volumio[818]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Oct 17 19:08:03 volumio321 volumio[818]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Oct 17 19:08:03 volumio321 volumio[818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:08:04 volumio321 go-librespot[1079]: time="2024-10-17T19:08:04+02:00" level=debug msg="fetched chunk 1/12, size: 524288" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:04 volumio321 go-librespot[1079]: time="2024-10-17T19:08:04+02:00" level=debug msg="fetched chunk 3/12, size: 524288" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:04 volumio321 go-librespot[1079]: time="2024-10-17T19:08:04+02:00" level=debug msg="fetched chunk 2/12, size: 524288" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:05 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:05 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1166. Oct 17 19:08:05 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:05 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:05 volumio321 volumio[4910]: Traceback (most recent call last): Oct 17 19:08:05 volumio321 volumio[4910]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:05 volumio321 volumio[4910]: from aiohttp import web Oct 17 19:08:05 volumio321 volumio[4910]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:06 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:06 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:08 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:08 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1167. Oct 17 19:08:08 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:08 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:08 volumio321 volumio[4912]: Traceback (most recent call last): Oct 17 19:08:08 volumio321 volumio[4912]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:08 volumio321 volumio[4912]: from aiohttp import web Oct 17 19:08:08 volumio321 volumio[4912]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:09 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:09 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:09 volumio321 volumio-remote-updater[500]: [2024-10-17 19:08:09] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 17 19:08:11 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:11 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1168. Oct 17 19:08:11 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:11 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:12 volumio321 volumio[4913]: Traceback (most recent call last): Oct 17 19:08:12 volumio321 volumio[4913]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:12 volumio321 volumio[4913]: from aiohttp import web Oct 17 19:08:12 volumio321 volumio[4913]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:12 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:12 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:14 volumio321 volumio-remote-updater[500]: [2024-10-17 19:08:14] [connect] Successful connection Oct 17 19:08:14 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:14 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1169. Oct 17 19:08:14 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:14 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:15 volumio321 volumio[4914]: Traceback (most recent call last): Oct 17 19:08:15 volumio321 volumio[4914]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:15 volumio321 volumio[4914]: from aiohttp import web Oct 17 19:08:15 volumio321 volumio[4914]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:15 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:15 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:17 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:17 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1170. Oct 17 19:08:17 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:17 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:18 volumio321 volumio[4915]: Traceback (most recent call last): Oct 17 19:08:18 volumio321 volumio[4915]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:18 volumio321 volumio[4915]: from aiohttp import web Oct 17 19:08:18 volumio321 volumio[4915]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:18 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:18 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:18 volumio321 go-librespot[1079]: time="2024-10-17T19:08:18+02:00" level=debug msg="fetched chunk 4/12, size: 524288" uri="spotify:track:7CHi4DtfK4heMlQaudCuHK" Oct 17 19:08:20 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:20 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1171. Oct 17 19:08:20 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:20 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:21 volumio321 volumio[4917]: Traceback (most recent call last): Oct 17 19:08:21 volumio321 volumio[4917]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:21 volumio321 volumio[4917]: from aiohttp import web Oct 17 19:08:21 volumio321 volumio[4917]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:21 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:21 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:23 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:23 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1172. Oct 17 19:08:23 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:23 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:24 volumio321 volumio[4940]: Traceback (most recent call last): Oct 17 19:08:24 volumio321 volumio[4940]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:24 volumio321 volumio[4940]: from aiohttp import web Oct 17 19:08:24 volumio321 volumio[4940]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:25 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:25 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 17 19:08:26 volumio321 sudo[4958]: volumio : unable to resolve host volumio321 Oct 17 19:08:26 volumio321 sudo[4958]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 17 19:08:26 volumio321 sudo[4958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 19:07 Oct 17 19:08:26 volumio321 sudo[4958]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:08:26 volumio321 volumio-remote-updater[500]: [2024-10-17 19:08:26] [connect] Successful connection Oct 17 19:08:27 volumio321 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Oct 17 19:08:27 volumio321 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1173. Oct 17 19:08:27 volumio321 systemd[1]: Stopped FusionDsp Daemon. Oct 17 19:08:27 volumio321 systemd[1]: Started FusionDsp Daemon. Oct 17 19:08:27 volumio321 volumio[4960]: Traceback (most recent call last): Oct 17 19:08:27 volumio321 volumio[4960]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Oct 17 19:08:27 volumio321 volumio[4960]: from aiohttp import web Oct 17 19:08:27 volumio321 volumio[4960]: ModuleNotFoundError: No module named 'aiohttp' Oct 17 19:08:28 volumio321 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Oct 17 19:08:28 volumio321 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"