-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-01 17:44:25 CEST. -- May 01 17:43:00 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:00 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 269. May 01 17:43:00 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:00 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:01 volumio5 volumio[3743]: Traceback (most recent call last): May 01 17:43:01 volumio5 volumio[3743]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:01 volumio5 volumio[3743]: from camilladsp import CamillaConnection May 01 17:43:01 volumio5 volumio[3743]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:01 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:01 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:01 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:43:01 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:03 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:03 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 270. May 01 17:43:03 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:03 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:03 volumio5 volumio[3758]: Traceback (most recent call last): May 01 17:43:03 volumio5 volumio[3758]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:03 volumio5 volumio[3758]: from camilladsp import CamillaConnection May 01 17:43:03 volumio5 volumio[3758]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:03 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:03 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:04 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:43:04 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:05 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:05 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 271. May 01 17:43:05 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:05 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:06 volumio5 volumio[3762]: Traceback (most recent call last): May 01 17:43:06 volumio5 volumio[3762]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:06 volumio5 volumio[3762]: from camilladsp import CamillaConnection May 01 17:43:06 volumio5 volumio[3762]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:06 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:06 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:08 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:08 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 272. May 01 17:43:08 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:08 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:08 volumio5 volumio[3768]: Traceback (most recent call last): May 01 17:43:08 volumio5 volumio[3768]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:08 volumio5 volumio[3768]: from camilladsp import CamillaConnection May 01 17:43:08 volumio5 volumio[3768]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:08 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:08 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:10 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:10 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 273. May 01 17:43:10 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:10 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:11 volumio5 volumio[3769]: Traceback (most recent call last): May 01 17:43:11 volumio5 volumio[3769]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:11 volumio5 volumio[3769]: from camilladsp import CamillaConnection May 01 17:43:11 volumio5 volumio[3769]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:11 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:11 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:13 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:13 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 274. May 01 17:43:13 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:13 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:13 volumio5 volumio[3784]: Traceback (most recent call last): May 01 17:43:13 volumio5 volumio[3784]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:13 volumio5 volumio[3784]: from camilladsp import CamillaConnection May 01 17:43:13 volumio5 volumio[3784]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:13 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:13 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:15 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:15 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 275. May 01 17:43:15 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:15 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:16 volumio5 volumio[3788]: Traceback (most recent call last): May 01 17:43:16 volumio5 volumio[3788]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:16 volumio5 volumio[3788]: from camilladsp import CamillaConnection May 01 17:43:16 volumio5 volumio[3788]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:16 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:16 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=trace msg="emitting websocket event: not_playing" May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7IRNF4CibPCfo30capMq1i" May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:04n3Bq8zdxNYWAsPNJKp4I","play_origin":"playlist"}} May 01 17:43:17 volumio5 volumio[1316]: error: Failed to decode event: not_playing May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=trace msg="emitting websocket event: will_play" May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7IRNF4CibPCfo30capMq1i","play_origin":"playlist"}} May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=info msg="loaded track \"Má Mě Ráda\" (paused: false, position: 0ms, duration: 200000ms, prefetched: true)" uri="spotify:track:7IRNF4CibPCfo30capMq1i" May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=trace msg="scheduling prefetch in 169s" May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=trace msg="emitting websocket event: metadata" May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7IRNF4CibPCfo30capMq1i","name":"Má Mě Ráda","artist_names":["Karel Gott","Josef Vobruba"],"album_name":"Hitparáda Filmových Melodií","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f5d47abc8a0be3207094b9ab","position":0,"duration":200000,"release_date":"year:2018 month:5 day:29","track_number":7,"disc_number":1}} May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 01 17:43:17 volumio5 go-librespot[1519]: time="2025-05-01T17:43:17+02:00" level=trace msg="emitting websocket event: playing" May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7IRNF4CibPCfo30capMq1i","play_origin":"playlist"}} May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: PUSH STATE SPOTIFY May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: {"status":"play","service":"spop","title":"Má Mě Ráda","artist":"Karel Gott, Josef Vobruba","album":"Hitparáda Filmových Melodií","albumart":"https://i.scdn.co/image/ab67616d00001e02f5d47abc8a0be3207094b9ab","uri":"spotify:track:7IRNF4CibPCfo30capMq1i","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 01 17:43:17 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:17 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:17 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:43:17 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: PUSH STATE SPOTIFY May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: {"status":"play","service":"spop","title":"Má Mě Ráda","artist":"Karel Gott, Josef Vobruba","album":"Hitparáda Filmových Melodií","albumart":"https://i.scdn.co/image/ab67616d00001e02f5d47abc8a0be3207094b9ab","uri":"spotify:track:7IRNF4CibPCfo30capMq1i","trackType":"spotify","seek":1000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 01 17:43:17 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:17 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:17 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:43:17 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:17 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:18 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:18 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 276. May 01 17:43:18 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:18 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:18 volumio5 volumio[3789]: Traceback (most recent call last): May 01 17:43:18 volumio5 volumio[3789]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:18 volumio5 volumio[3789]: from camilladsp import CamillaConnection May 01 17:43:18 volumio5 volumio[3789]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:18 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:18 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:19 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:43:19 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:20 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:20 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 277. May 01 17:43:20 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:20 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:21 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:43:21 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:21 volumio5 volumio[3790]: Traceback (most recent call last): May 01 17:43:21 volumio5 volumio[3790]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:21 volumio5 volumio[3790]: from camilladsp import CamillaConnection May 01 17:43:21 volumio5 volumio[3790]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:21 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:21 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:22 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:43:22 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:23 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 17:43:23 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 17:43:23 volumio5 volumio[1316]: info: Discovery: Getting this device information May 01 17:43:23 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:23 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 17:43:23 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:23 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 278. May 01 17:43:23 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:23 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:23 volumio5 volumio[3846]: Traceback (most recent call last): May 01 17:43:23 volumio5 volumio[3846]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:23 volumio5 volumio[3846]: from camilladsp import CamillaConnection May 01 17:43:23 volumio5 volumio[3846]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:23 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:23 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:25 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:25 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 279. May 01 17:43:25 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:25 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:26 volumio5 volumio[3851]: Traceback (most recent call last): May 01 17:43:26 volumio5 volumio[3851]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:26 volumio5 volumio[3851]: from camilladsp import CamillaConnection May 01 17:43:26 volumio5 volumio[3851]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:26 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:26 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:28 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:28 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 280. May 01 17:43:28 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:28 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:28 volumio5 volumio[3853]: Traceback (most recent call last): May 01 17:43:28 volumio5 volumio[3853]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:28 volumio5 volumio[3853]: from camilladsp import CamillaConnection May 01 17:43:28 volumio5 volumio[3853]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:28 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:28 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:28 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:43:28 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:28 volumio5 volumio[1316]: info: Preloading song: upnp/http://192.168.2.129:8200/ctl/ContentDir@1$7$3$0 May 01 17:43:29 volumio5 volumio[1316]: info: Exploding uri upnp/http://192.168.2.129:8200/ctl/ContentDir@1$7$3$0 in service upnp_browser May 01 17:43:30 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:30 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 281. May 01 17:43:30 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:30 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:31 volumio5 volumio[3854]: Traceback (most recent call last): May 01 17:43:31 volumio5 volumio[3854]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:31 volumio5 volumio[3854]: from camilladsp import CamillaConnection May 01 17:43:31 volumio5 volumio[3854]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:31 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:31 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:32 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::ClearQueue May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::stop May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::serviceStop May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::serviceStop May 01 17:43:32 volumio5 volumio[1316]: info: Spotify Stop May 01 17:43:32 volumio5 volumio[1316]: SPOTIFY: SPOTIFY STOP May 01 17:43:32 volumio5 volumio[1316]: SPOTIFY: {"status":"play","title":"Má Mě Ráda","artist":"Karel Gott, Josef Vobruba","album":"Hitparáda Filmových Melodií","albumart":"https://i.scdn.co/image/ab67616d00001e02f5d47abc8a0be3207094b9ab","uri":"spotify:track:7IRNF4CibPCfo30capMq1i","trackType":"spotify","codec":"ogg","seek":1000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":79,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} May 01 17:43:32 volumio5 volumio[1316]: info: Sending Spotify command to local API: /player/pause May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::clearPlayQueue May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::saveQueue May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushQueue May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::addQueueItems May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::addQueueItems May 01 17:43:32 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:43:32 volumio5 volumio[1316]: info: Adding Item to queue: upnp/http://192.168.2.129:8200/ctl/ContentDir@1$7$3$0 May 01 17:43:32 volumio5 volumio[1316]: info: Using cached record of: upnp/http://192.168.2.129:8200/ctl/ContentDir@1$7$3$0 May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushQueue May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::saveQueue May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::updateTrackBlock May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::getTrackBlock May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPlay May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::play index 0 May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::stop May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::stPlaybackTimer May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::updateTrackBlock May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::getTrackBlock May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 6 May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:43:32 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::serviceStop May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 6 May 01 17:43:32 volumio5 volumio[1316]: info: ControllerMpd::stop May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand stop May 01 17:43:32 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:32 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:32 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:32 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:32 volumio5 volumio[1316]: info: sendMpdCommand stop took 5 milliseconds May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::play index undefined May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 0 May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::startPlaybackTimer May 01 17:43:32 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 0 May 01 17:43:32 volumio5 volumio[1316]: info: [1746114212855] ControllerUPNPBrowser::clearAddPlayTrack May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand stop May 01 17:43:32 volumio5 volumio[1316]: info: sendMpdCommand stop took 0 milliseconds May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand clear May 01 17:43:32 volumio5 volumio[1316]: info: sendMpdCommand clear took 1 milliseconds May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.129:8200/MediaItems/71.mp3" May 01 17:43:32 volumio5 volumio[1316]: info: May 01 17:43:32 volumio5 volumio[1316]: ---------------------------- MPD announces system playlist update May 01 17:43:32 volumio5 volumio[1316]: info: Ignoring MPD Status Update May 01 17:43:32 volumio5 volumio[1316]: info: May 01 17:43:32 volumio5 volumio[1316]: ---------------------------- MPD announces system playlist update May 01 17:43:32 volumio5 volumio[1316]: info: Ignoring MPD Status Update May 01 17:43:32 volumio5 volumio[1316]: error: Upnp client error: Error: This socket has been ended by the other party May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.129:8200/MediaItems/71.mp3" May 01 17:43:32 volumio5 volumio[1316]: error: updateQueue error: null May 01 17:43:32 volumio5 volumio[1316]: error: updateQueue error: null May 01 17:43:32 volumio5 volumio[1316]: info: May 01 17:43:32 volumio5 volumio[1316]: ---------------------------- MPD announces system playlist update May 01 17:43:32 volumio5 volumio[1316]: info: Ignoring MPD Status Update May 01 17:43:32 volumio5 volumio[1316]: info: ------------------------------ 19ms May 01 17:43:32 volumio5 volumio[1316]: info: ------------------------------ 17ms May 01 17:43:32 volumio5 volumio[1316]: info: sendMpdCommand add "http://192.168.2.129:8200/MediaItems/71.mp3" took 1 milliseconds May 01 17:43:32 volumio5 volumio[1316]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand play May 01 17:43:32 volumio5 volumio[1316]: info: May 01 17:43:32 volumio5 volumio[1316]: ---------------------------- MPD announces system playlist update May 01 17:43:32 volumio5 volumio[1316]: info: Ignoring MPD Status Update May 01 17:43:32 volumio5 volumio[1316]: info: ------------------------------ 1ms May 01 17:43:32 volumio5 volumio[1316]: info: sendMpdCommand play took 2 milliseconds May 01 17:43:32 volumio5 volumio[1316]: info: ------------------------------ 1ms May 01 17:43:32 volumio5 volumio[1316]: info: May 01 17:43:32 volumio5 volumio[1316]: ---------------------------- MPD announces state update: player May 01 17:43:32 volumio5 volumio[1316]: info: ControllerMpd::getState May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand status May 01 17:43:32 volumio5 volumio[1316]: info: May 01 17:43:32 volumio5 volumio[1316]: ---------------------------- MPD announces state update: player May 01 17:43:32 volumio5 volumio[1316]: info: ControllerMpd::getState May 01 17:43:32 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand status May 01 17:43:32 volumio5 volumio[1316]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 01 17:43:32 volumio5 volumio[1316]: info: FusionDsp - ---- read samplerate from file: 352800 May 01 17:43:32 volumio5 volumio[1316]: info: camilladsp stopping service pid 2163... May 01 17:43:32 volumio5 volumio[1316]: info: camilladsp service terminated, instance 1 May 01 17:43:32 volumio5 volumio[1316]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 17:43:33 volumio5 volumio[1316]: info: camilladsp service started and running in background, instance 1 May 01 17:43:33 volumio5 volumio[1316]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 01 17:43:33 volumio5 volumio[1316]: info: FusionDsp - ---- read samplerate from file: 352800 May 01 17:43:33 volumio5 volumio[1316]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 17:43:33 volumio5 volumio[1316]: info: May 01 17:43:33 volumio5 volumio[1316]: ---------------------------- MPD announces system playlist update May 01 17:43:33 volumio5 volumio[1316]: info: Ignoring MPD Status Update May 01 17:43:33 volumio5 volumio[1316]: info: May 01 17:43:33 volumio5 volumio[1316]: ---------------------------- MPD announces state update: player May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand status took 390 milliseconds May 01 17:43:33 volumio5 volumio[1316]: info: ControllerMpd::getState May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand status May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseState May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseState May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:43:33 volumio5 volumio[1316]: info: May 01 17:43:33 volumio5 volumio[1316]: ---------------------------- MPD announces system playlist update May 01 17:43:33 volumio5 volumio[1316]: info: Ignoring MPD Status Update May 01 17:43:33 volumio5 volumio[1316]: info: May 01 17:43:33 volumio5 volumio[1316]: ---------------------------- MPD announces state update: player May 01 17:43:33 volumio5 volumio[1316]: info: ControllerMpd::getState May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand status May 01 17:43:33 volumio5 volumio[1316]: info: ------------------------------ 6ms May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand status took 5 milliseconds May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand playlistinfo took 4 milliseconds May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand playlistinfo took 4 milliseconds May 01 17:43:33 volumio5 volumio[1316]: info: ------------------------------ 3ms May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand status took 3 milliseconds May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseState May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseTrackInfo May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseTrackInfo May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseState May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 17:43:33 volumio5 volumio[1316]: info: ControllerMpd::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 0 May 01 17:43:33 volumio5 volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":68,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Planeta je zachráněna!","artist":"Filmový symfonický orchestr (FISYO)/Štěpán Koníček, Skupina Elektrovox","album":"Návštěvníci / Létající Čestmír","uri":"http://192.168.2.129:8200/MediaItems/71.mp3","trackType":"mp3"} May 01 17:43:33 volumio5 volumio[1316]: verbose: CURRENT POSITION 0 May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState stateService play May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState currentStatus stop May 01 17:43:33 volumio5 volumio[1316]: info: ControllerMpd::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 0 May 01 17:43:33 volumio5 volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":68,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Planeta je zachráněna!","artist":"Filmový symfonický orchestr (FISYO)/Štěpán Koníček, Skupina Elektrovox","album":"Návštěvníci / Létající Čestmír","uri":"http://192.168.2.129:8200/MediaItems/71.mp3","trackType":"mp3"} May 01 17:43:33 volumio5 volumio[1316]: verbose: CURRENT POSITION 0 May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState stateService play May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState currentStatus play May 01 17:43:33 volumio5 volumio[1316]: info: Received an update from plugin. extracting info from payload May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 volumio[1316]: info: ------------------------------ 404ms May 01 17:43:33 volumio5 volumio[1316]: info: ------------------------------ 406ms May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[1316]: error: FusionDsp - WebSocket error: [object Object] May 01 17:43:33 volumio5 volumio[1316]: info: camilladsp spawned new process with pid 3879, instance 1, run: true May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand playlistinfo took 24 milliseconds May 01 17:43:33 volumio5 volumio[1316]: info: sendMpdCommand playlistinfo took 23 milliseconds May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseTrackInfo May 01 17:43:33 volumio5 volumio[1316]: verbose: ControllerMpd::parseTrackInfo May 01 17:43:33 volumio5 volumio[1316]: info: ControllerMpd::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 0 May 01 17:43:33 volumio5 volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189,"duration":68,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Planeta je zachráněna!","artist":"Filmový symfonický orchestr (FISYO)/Štěpán Koníček, Skupina Elektrovox","album":"Návštěvníci / Létající Čestmír","uri":"http://192.168.2.129:8200/MediaItems/71.mp3","trackType":"mp3"} May 01 17:43:33 volumio5 volumio[1316]: verbose: CURRENT POSITION 0 May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState stateService play May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState currentStatus play May 01 17:43:33 volumio5 volumio[1316]: info: Received an update from plugin. extracting info from payload May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 volumio[1316]: info: ControllerMpd::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getTrack 0 May 01 17:43:33 volumio5 volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189,"duration":68,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Planeta je zachráněna!","artist":"Filmový symfonický orchestr (FISYO)/Štěpán Koníček, Skupina Elektrovox","album":"Návštěvníci / Létající Čestmír","uri":"http://192.168.2.129:8200/MediaItems/71.mp3","trackType":"mp3"} May 01 17:43:33 volumio5 volumio[1316]: verbose: CURRENT POSITION 0 May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState stateService play May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::syncState currentStatus play May 01 17:43:33 volumio5 volumio[1316]: info: Received an update from plugin. extracting info from payload May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 01 17:43:33 volumio5 volumio[1316]: info: ------------------------------ 45ms May 01 17:43:33 volumio5 volumio[1316]: info: ------------------------------ 43ms May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:33 volumio5 go-librespot[1519]: time="2025-05-01T17:43:33+02:00" level=debug msg="pause track at 16093ms" May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:33 volumio5 go-librespot[1519]: time="2025-05-01T17:43:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 01 17:43:33 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:33 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 282. May 01 17:43:33 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:33 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:33 volumio5 go-librespot[1519]: time="2025-05-01T17:43:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 01 17:43:33 volumio5 go-librespot[1519]: time="2025-05-01T17:43:33+02:00" level=trace msg="emitting websocket event: paused" May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7IRNF4CibPCfo30capMq1i","play_origin":"playlist"}} May 01 17:43:33 volumio5 volumio[1316]: info: Spotify is playing in volatile mode May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: UNSET VOLATILE May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: {"status":"play","position":0,"title":"Planeta je zachráněna!","artist":"Filmový symfonický orchestr (FISYO)/Štěpán Koníček, Skupina Elektrovox","album":"Návštěvníci / Létající Čestmír","albumart":"http://192.168.2.129:8200/AlbumArt/3-71.jpg","uri":"http://192.168.2.129:8200/MediaItems/71.mp3","trackType":"mp3","seek":0,"duration":68,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"bitrate":"256 Kbps","random":false,"repeat":false,"repeatSingle":false,"consume":true,"volume":79,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"mpd"} May 01 17:43:33 volumio5 volumio[1316]: info: Setting Spotify stop after unset volatile call May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: PUSH STATE SPOTIFY May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: {"status":"pause","service":"spop","title":"Má Mě Ráda","artist":"Karel Gott, Josef Vobruba","album":"Hitparáda Filmových Melodií","albumart":"https://i.scdn.co/image/ab67616d00001e02f5d47abc8a0be3207094b9ab","uri":"spotify:track:7IRNF4CibPCfo30capMq1i","trackType":"spotify","seek":16000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::servicePushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:43:33 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 79 May 01 17:43:33 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: false May 01 17:43:33 volumio5 volumio[3886]: Traceback (most recent call last): May 01 17:43:33 volumio5 volumio[3886]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:33 volumio5 volumio[3886]: from camilladsp import CamillaConnection May 01 17:43:33 volumio5 volumio[3886]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:33 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:33 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:34 volumio5 volumio[1316]: info: Spotify Stop May 01 17:43:34 volumio5 volumio[1316]: SPOTIFY: SPOTIFY STOP May 01 17:43:34 volumio5 volumio[1316]: SPOTIFY: {"status":"pause","title":"Má Mě Ráda","artist":"Karel Gott, Josef Vobruba","album":"Hitparáda Filmových Melodií","albumart":"https://i.scdn.co/image/ab67616d00001e02f5d47abc8a0be3207094b9ab","uri":"spotify:track:7IRNF4CibPCfo30capMq1i","trackType":"spotify","codec":"ogg","seek":16000,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":79,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} May 01 17:43:35 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:35 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 283. May 01 17:43:35 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:35 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:36 volumio5 volumio[3899]: Traceback (most recent call last): May 01 17:43:36 volumio5 volumio[3899]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:36 volumio5 volumio[3899]: from camilladsp import CamillaConnection May 01 17:43:36 volumio5 volumio[3899]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:36 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:36 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:38 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:38 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 284. May 01 17:43:38 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:38 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:38 volumio5 volumio[3905]: Traceback (most recent call last): May 01 17:43:38 volumio5 volumio[3905]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:38 volumio5 volumio[3905]: from camilladsp import CamillaConnection May 01 17:43:38 volumio5 volumio[3905]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:38 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:38 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:40 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:40 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 285. May 01 17:43:40 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:40 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:41 volumio5 volumio[3912]: Traceback (most recent call last): May 01 17:43:41 volumio5 volumio[3912]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:41 volumio5 volumio[3912]: from camilladsp import CamillaConnection May 01 17:43:41 volumio5 volumio[3912]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:41 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:41 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:42 volumio5 volumio[1316]: info: VolumeController::SetAlsaVolume90 May 01 17:43:42 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:42 volumio5 volumio[1316]: info: CoreStateMachine::pushState May 01 17:43:42 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 17:43:42 volumio5 volumio[1316]: info: CoreCommandRouter::volumioPushState May 01 17:43:42 volumio5 volumio[1316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 01 17:43:42 volumio5 volumio[1316]: SPOTIFY: SPOTIFY VOLUME 80 May 01 17:43:42 volumio5 volumio[1316]: SPOTIFY: VOLUMIO VOLUME 90 May 01 17:43:42 volumio5 volumio[1316]: SPOTIFY: DELTA VOLUME ENOUGH: true May 01 17:43:42 volumio5 volumio[1316]: info: Setting Spotify Volume from Volumio: 90 May 01 17:43:43 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:43 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 286. May 01 17:43:43 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:43 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:43 volumio5 volumio[3932]: Traceback (most recent call last): May 01 17:43:43 volumio5 volumio[3932]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:43 volumio5 volumio[3932]: from camilladsp import CamillaConnection May 01 17:43:43 volumio5 volumio[3932]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:43 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:43 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:44 volumio5 volumio[1316]: SPOTIFY: SETTING SPOTIFY VOLUME 90 May 01 17:43:44 volumio5 volumio[1316]: info: Sending Spotify command with payload to local API: /player/volume May 01 17:43:44 volumio5 go-librespot[1519]: time="2025-05-01T17:43:44+02:00" level=debug msg="update volume to 58981/65535" May 01 17:43:44 volumio5 go-librespot[1519]: time="2025-05-01T17:43:44+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 01 17:43:44 volumio5 go-librespot[1519]: time="2025-05-01T17:43:44+02:00" level=trace msg="emitting websocket event: volume" May 01 17:43:44 volumio5 volumio[1316]: SPOTIFY: received: {"type":"volume","data":{"value":90,"max":100}} May 01 17:43:44 volumio5 volumio[1316]: SPOTIFY: RECEIVED SPOTIFY VOLUME 90 May 01 17:43:45 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:45 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 287. May 01 17:43:45 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:45 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:46 volumio5 volumio[3940]: Traceback (most recent call last): May 01 17:43:46 volumio5 volumio[3940]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:46 volumio5 volumio[3940]: from camilladsp import CamillaConnection May 01 17:43:46 volumio5 volumio[3940]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:46 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:46 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:48 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:48 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 288. May 01 17:43:48 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:48 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:48 volumio5 volumio[3948]: Traceback (most recent call last): May 01 17:43:48 volumio5 volumio[3948]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:48 volumio5 volumio[3948]: from camilladsp import CamillaConnection May 01 17:43:48 volumio5 volumio[3948]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:48 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:48 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:50 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:50 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 289. May 01 17:43:50 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:50 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:51 volumio5 volumio[3953]: Traceback (most recent call last): May 01 17:43:51 volumio5 volumio[3953]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:51 volumio5 volumio[3953]: from camilladsp import CamillaConnection May 01 17:43:51 volumio5 volumio[3953]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:51 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:51 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:53 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 17:43:53 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 17:43:53 volumio5 volumio[1316]: info: Discovery: Getting this device information May 01 17:43:53 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:53 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 17:43:53 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:53 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 290. May 01 17:43:53 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:53 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:53 volumio5 volumio[3974]: Traceback (most recent call last): May 01 17:43:53 volumio5 volumio[3974]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:53 volumio5 volumio[3974]: from camilladsp import CamillaConnection May 01 17:43:53 volumio5 volumio[3974]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:53 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:53 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:53 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:54 volumio5 sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 17:43:54 volumio5 sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:43:54 volumio5 sudo[3978]: pam_unix(sudo:session): session closed for user root May 01 17:43:54 volumio5 sudo[3981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 17:43:54 volumio5 sudo[3981]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:43:54 volumio5 sudo[3981]: pam_unix(sudo:session): session closed for user root May 01 17:43:54 volumio5 volumio[1316]: verbose: New Socket.io Connection to 192.168.2.129 from 192.168.2.101 UA: Mozilla/5.0 (Linux; Android 13; 2201116SG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetVisibleSources May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 17:43:54 volumio5 sudo[3987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 17:43:54 volumio5 sudo[3987]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:43:54 volumio5 sudo[3987]: pam_unix(sudo:session): session closed for user root May 01 17:43:54 volumio5 sudo[3989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 17:43:54 volumio5 sudo[3989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 17:43:54 volumio5 sudo[3989]: pam_unix(sudo:session): session closed for user root May 01 17:43:54 volumio5 volumio[1316]: verbose: New Socket.io Connection to 192.168.2.129 from 192.168.2.101 UA: Mozilla/5.0 (Linux; Android 13; 2201116SG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 17:43:54 volumio5 volumio[1316]: info: Listing playlists May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 17:43:54 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 17:43:55 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:55 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 291. May 01 17:43:55 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:55 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:56 volumio5 volumio[3994]: Traceback (most recent call last): May 01 17:43:56 volumio5 volumio[3994]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:56 volumio5 volumio[3994]: from camilladsp import CamillaConnection May 01 17:43:56 volumio5 volumio[3994]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:56 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:56 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:43:58 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetVisibleSources May 01 17:43:58 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 17:43:58 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetQueue May 01 17:43:58 volumio5 volumio[1316]: info: CoreStateMachine::getQueue May 01 17:43:58 volumio5 volumio[1316]: info: CorePlayQueue::getQueue May 01 17:43:58 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:43:58 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 292. May 01 17:43:58 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:43:58 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:43:58 volumio5 volumio[4001]: Traceback (most recent call last): May 01 17:43:58 volumio5 volumio[4001]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:43:58 volumio5 volumio[4001]: from camilladsp import CamillaConnection May 01 17:43:58 volumio5 volumio[4001]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:43:58 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:43:58 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:00 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:00 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 293. May 01 17:44:00 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:00 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:01 volumio5 volumio[4006]: Traceback (most recent call last): May 01 17:44:01 volumio5 volumio[4006]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:01 volumio5 volumio[4006]: from camilladsp import CamillaConnection May 01 17:44:01 volumio5 volumio[4006]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:01 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:01 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:02 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:02 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:03 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:03 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 294. May 01 17:44:03 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:03 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:03 volumio5 volumio[4027]: Traceback (most recent call last): May 01 17:44:03 volumio5 volumio[4027]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:03 volumio5 volumio[4027]: from camilladsp import CamillaConnection May 01 17:44:03 volumio5 volumio[4027]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:03 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:03 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:04 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:04 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:05 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:05 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 295. May 01 17:44:05 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:05 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:06 volumio5 volumio[4036]: Traceback (most recent call last): May 01 17:44:06 volumio5 volumio[4036]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:06 volumio5 volumio[4036]: from camilladsp import CamillaConnection May 01 17:44:06 volumio5 volumio[4036]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:06 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:06 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:07 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:07 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:08 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:08 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 296. May 01 17:44:08 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:08 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:08 volumio5 volumio[4044]: Traceback (most recent call last): May 01 17:44:08 volumio5 volumio[4044]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:08 volumio5 volumio[4044]: from camilladsp import CamillaConnection May 01 17:44:08 volumio5 volumio[4044]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:08 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:08 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:08 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:08 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:10 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:10 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 297. May 01 17:44:10 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:10 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:11 volumio5 volumio[4049]: Traceback (most recent call last): May 01 17:44:11 volumio5 volumio[4049]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:11 volumio5 volumio[4049]: from camilladsp import CamillaConnection May 01 17:44:11 volumio5 volumio[4049]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:11 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:11 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:11 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:11 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 17:44:12 volumio5 volumio[1316]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 17:44:12 volumio5 volumio[1316]: info: Received Get System Version May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 17:44:12 volumio5 volumio[1316]: info: Received Get System Info May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 17:44:12 volumio5 volumio[1316]: info: Discovery: Getting this device information May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:44:12 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 17:44:13 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:13 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 298. May 01 17:44:13 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:13 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:13 volumio5 volumio[4070]: Traceback (most recent call last): May 01 17:44:13 volumio5 volumio[4070]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:13 volumio5 volumio[4070]: from camilladsp import CamillaConnection May 01 17:44:13 volumio5 volumio[4070]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:13 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:13 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:15 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:15 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:15 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:15 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 299. May 01 17:44:15 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:15 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:16 volumio5 volumio[4078]: Traceback (most recent call last): May 01 17:44:16 volumio5 volumio[4078]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:16 volumio5 volumio[4078]: from camilladsp import CamillaConnection May 01 17:44:16 volumio5 volumio[4078]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:16 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:16 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:16 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:16 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:18 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:18 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 300. May 01 17:44:18 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:18 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:18 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:18 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:18 volumio5 volumio[4085]: Traceback (most recent call last): May 01 17:44:18 volumio5 volumio[4085]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:18 volumio5 volumio[4085]: from camilladsp import CamillaConnection May 01 17:44:18 volumio5 volumio[4085]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:18 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:18 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:20 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:20 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 301. May 01 17:44:20 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:20 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:21 volumio5 volumio[4090]: Traceback (most recent call last): May 01 17:44:21 volumio5 volumio[4090]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:21 volumio5 volumio[4090]: from camilladsp import CamillaConnection May 01 17:44:21 volumio5 volumio[4090]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:21 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:21 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:21 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:21 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:22 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:22 volumio5 volumio[1316]: info: Preload queue cleared May 01 17:44:23 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 17:44:23 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 17:44:23 volumio5 volumio[1316]: info: Discovery: Getting this device information May 01 17:44:23 volumio5 volumio[1316]: info: CoreCommandRouter::volumioGetState May 01 17:44:23 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 17:44:23 volumio5 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 01 17:44:23 volumio5 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 302. May 01 17:44:23 volumio5 systemd[1]: Stopped FusionDsp Daemon. May 01 17:44:23 volumio5 systemd[1]: Started FusionDsp Daemon. May 01 17:44:23 volumio5 volumio[4152]: Traceback (most recent call last): May 01 17:44:23 volumio5 volumio[4152]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 01 17:44:23 volumio5 volumio[4152]: from camilladsp import CamillaConnection May 01 17:44:23 volumio5 volumio[4152]: ModuleNotFoundError: No module named 'camilladsp' May 01 17:44:23 volumio5 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 17:44:23 volumio5 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 17:44:25 volumio5 volumio[1316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 17:44:25 volumio5 volumio[1316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 17:44:25 volumio5 volumio[1316]: TypeError: Cannot read property 'length' of undefined May 01 17:44:25 volumio5 volumio[1316]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 01 17:44:25 volumio5 volumio[1316]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 01 17:44:25 volumio5 volumio[1316]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 01 17:44:25 volumio5 volumio[1316]: at Parser.emit (events.js:315:20) May 01 17:44:25 volumio5 volumio[1316]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 01 17:44:25 volumio5 volumio[1316]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 01 17:44:25 volumio5 volumio[1316]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 01 17:44:25 volumio5 volumio[1316]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 01 17:44:25 volumio5 volumio[1316]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 01 17:44:25 volumio5 volumio[1316]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 01 17:44:25 volumio5 volumio[1316]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 01 17:44:25 volumio5 volumio[1316]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 01 17:44:25 volumio5 volumio[1316]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 01 17:44:25 volumio5 volumio[1316]: at IncomingMessage.emit (events.js:327:22) May 01 17:44:25 volumio5 volumio[1316]: at endReadableNT (internal/streams/readable.js:1327:12) May 01 17:44:25 volumio5 volumio[1316]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 01 17:44:25 volumio5 volumio[1316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 17:44:25 volumio5 sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 17:43 May 01 17:44:25 volumio5 sudo[4167]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"