-- Logs begin at Sun 2025-03-02 16:20:59 CET, end at Sun 2025-03-02 17:06:56 CET. -- Mar 02 17:05:00 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14722, instance 2, run: true Mar 02 17:05:00 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14729, instance 2, run: true Mar 02 17:05:00 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14736, instance 2, run: true Mar 02 17:05:00 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14745, instance 2, run: true Mar 02 17:05:01 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14752, instance 2, run: true Mar 02 17:05:01 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14759, instance 2, run: true Mar 02 17:05:01 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14766, instance 2, run: true Mar 02 17:05:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14775, instance 2, run: true Mar 02 17:05:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14782, instance 2, run: true Mar 02 17:05:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14790, instance 2, run: true Mar 02 17:05:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14797, instance 2, run: true Mar 02 17:05:02 volumiodk volumio[950]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/armhf Mar 02 17:05:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14807, instance 2, run: true Mar 02 17:05:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14815, instance 2, run: true Mar 02 17:05:03 volumiodk volumio[14698]: Traceback (most recent call last): Mar 02 17:05:03 volumiodk volumio[14698]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Mar 02 17:05:03 volumiodk volumio[14698]: from camilladsp import CamillaConnection Mar 02 17:05:03 volumiodk volumio[14698]: ModuleNotFoundError: No module named 'camilladsp' Mar 02 17:05:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14826, instance 2, run: true Mar 02 17:05:03 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:03 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14848, instance 2, run: true Mar 02 17:05:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14868, instance 2, run: true Mar 02 17:05:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14899, instance 2, run: true Mar 02 17:05:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14906, instance 2, run: true Mar 02 17:05:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14913, instance 2, run: true Mar 02 17:05:05 volumiodk volumio[950]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpdoutput/volumio/buster/armhf Mar 02 17:05:05 volumiodk volumio[950]: info: Folder /tmp/plugins removed Mar 02 17:05:05 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14922, instance 2, run: true Mar 02 17:05:05 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14931, instance 2, run: true Mar 02 17:05:05 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14938, instance 2, run: true Mar 02 17:05:05 volumiodk volumio[950]: info: Check plugin dependencies Mar 02 17:05:05 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 17:05:05 volumiodk volumio[950]: info: Checking if plugin already exists Mar 02 17:05:05 volumiodk volumio[950]: info: Rename folder Mar 02 17:05:06 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14950, instance 2, run: true Mar 02 17:05:06 volumiodk volumio[950]: info: Folder /tmp/downloaded_plugin.zip removed Mar 02 17:05:06 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:06 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 18. Mar 02 17:05:06 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:06 volumiodk volumio[950]: info: Move to category Mar 02 17:05:06 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:06 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14960, instance 2, run: true Mar 02 17:05:06 volumiodk volumio[950]: info: Checking if install.sh is present Mar 02 17:05:06 volumiodk volumio[950]: info: Executing install.sh Mar 02 17:05:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14972, instance 2, run: true Mar 02 17:05:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14981, instance 2, run: true Mar 02 17:05:07 volumiodk sudo[14971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/install.sh Mar 02 17:05:07 volumiodk sudo[14971]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:05:07 volumiodk sudo[14971]: pam_unix(sudo:session): session closed for user root Mar 02 17:05:07 volumiodk volumio[950]: info: Installing mpd http output Mar 02 17:05:07 volumiodk volumio[950]: Plugin install end detected on script Mar 02 17:05:07 volumiodk volumio[950]: info: Install script completed Mar 02 17:05:07 volumiodk volumio[950]: info: Adding reference to registry Mar 02 17:05:07 volumiodk volumio[950]: info: Done installing plugin. Mar 02 17:05:07 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 17:05:07 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 02 17:05:07 volumiodk volumio[950]: info: Folder /tmp/plugins removed Mar 02 17:05:07 volumiodk volumio[950]: info: Folder /tmp/downloaded_plugin.zip removed Mar 02 17:05:07 volumiodk volumio[950]: info: Folder /data/temp removed Mar 02 17:05:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14989, instance 2, run: true Mar 02 17:05:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 14996, instance 2, run: true Mar 02 17:05:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15005, instance 2, run: true Mar 02 17:05:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15012, instance 2, run: true Mar 02 17:05:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15019, instance 2, run: true Mar 02 17:05:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15026, instance 2, run: true Mar 02 17:05:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15035, instance 2, run: true Mar 02 17:05:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15042, instance 2, run: true Mar 02 17:05:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15049, instance 2, run: true Mar 02 17:05:10 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15056, instance 2, run: true Mar 02 17:05:10 volumiodk volumio[950]: info: Enabling plugin mpdoutput Mar 02 17:05:10 volumiodk volumio[950]: info: Loading plugin "mpdoutput"... Mar 02 17:05:10 volumiodk volumio[14951]: Traceback (most recent call last): Mar 02 17:05:10 volumiodk volumio[14951]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Mar 02 17:05:10 volumiodk volumio[14951]: from camilladsp import CamillaConnection Mar 02 17:05:10 volumiodk volumio[14951]: ModuleNotFoundError: No module named 'camilladsp' Mar 02 17:05:11 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:11 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:11 volumiodk volumio[950]: info: PLUGIN START: mpdoutput Mar 02 17:05:11 volumiodk volumio[950]: info: Done. Mar 02 17:05:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15063, instance 2, run: true Mar 02 17:05:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15072, instance 2, run: true Mar 02 17:05:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15079, instance 2, run: true Mar 02 17:05:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15086, instance 2, run: true Mar 02 17:05:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15093, instance 2, run: true Mar 02 17:05:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15100, instance 2, run: true Mar 02 17:05:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15107, instance 2, run: true Mar 02 17:05:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15115, instance 2, run: true Mar 02 17:05:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15124, instance 2, run: true Mar 02 17:05:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15131, instance 2, run: true Mar 02 17:05:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15138, instance 2, run: true Mar 02 17:05:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15145, instance 2, run: true Mar 02 17:05:13 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:13 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 19. Mar 02 17:05:13 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:13 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15153, instance 2, run: true Mar 02 17:05:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15161, instance 2, run: true Mar 02 17:05:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15170, instance 2, run: true Mar 02 17:05:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15177, instance 2, run: true Mar 02 17:05:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15184, instance 2, run: true Mar 02 17:05:14 volumiodk volumio[950]: info: Enabling plugin mpdoutput Mar 02 17:05:14 volumiodk volumio[950]: info: Loading plugin "mpdoutput"... Mar 02 17:05:14 volumiodk volumio[950]: info: PLUGIN START: mpdoutput Mar 02 17:05:14 volumiodk volumio[950]: info: Done. Mar 02 17:05:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15191, instance 2, run: true Mar 02 17:05:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15200, instance 2, run: true Mar 02 17:05:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15207, instance 2, run: true Mar 02 17:05:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15214, instance 2, run: true Mar 02 17:05:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15222, instance 2, run: true Mar 02 17:05:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15231, instance 2, run: true Mar 02 17:05:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15238, instance 2, run: true Mar 02 17:05:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15247, instance 2, run: true Mar 02 17:05:16 volumiodk volumio[15152]: Traceback (most recent call last): Mar 02 17:05:16 volumiodk volumio[15152]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Mar 02 17:05:16 volumiodk volumio[15152]: from camilladsp import CamillaConnection Mar 02 17:05:16 volumiodk volumio[15152]: ModuleNotFoundError: No module named 'camilladsp' Mar 02 17:05:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15254, instance 2, run: true Mar 02 17:05:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15263, instance 2, run: true Mar 02 17:05:17 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:17 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15270, instance 2, run: true Mar 02 17:05:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15277, instance 2, run: true Mar 02 17:05:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15284, instance 2, run: true Mar 02 17:05:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15291, instance 2, run: true Mar 02 17:05:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15298, instance 2, run: true Mar 02 17:05:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15305, instance 2, run: true Mar 02 17:05:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15314, instance 2, run: true Mar 02 17:05:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15321, instance 2, run: true Mar 02 17:05:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15328, instance 2, run: true Mar 02 17:05:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15335, instance 2, run: true Mar 02 17:05:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15342, instance 2, run: true Mar 02 17:05:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15350, instance 2, run: true Mar 02 17:05:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15357, instance 2, run: true Mar 02 17:05:19 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:19 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 20. Mar 02 17:05:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15366, instance 2, run: true Mar 02 17:05:19 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:19 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15374, instance 2, run: true Mar 02 17:05:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15381, instance 2, run: true Mar 02 17:05:20 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15388, instance 2, run: true Mar 02 17:05:20 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15395, instance 2, run: true Mar 02 17:05:20 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15404, instance 2, run: true Mar 02 17:05:20 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15411, instance 2, run: true Mar 02 17:05:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15421, instance 2, run: true Mar 02 17:05:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15428, instance 2, run: true Mar 02 17:05:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15437, instance 2, run: true Mar 02 17:05:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15444, instance 2, run: true Mar 02 17:05:22 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15451, instance 2, run: true Mar 02 17:05:22 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15458, instance 2, run: true Mar 02 17:05:22 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15467, instance 2, run: true Mar 02 17:05:22 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15474, instance 2, run: true Mar 02 17:05:23 volumiodk volumio[15369]: Traceback (most recent call last): Mar 02 17:05:23 volumiodk volumio[15369]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Mar 02 17:05:23 volumiodk volumio[15369]: from camilladsp import CamillaConnection Mar 02 17:05:23 volumiodk volumio[15369]: ModuleNotFoundError: No module named 'camilladsp' Mar 02 17:05:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15481, instance 2, run: true Mar 02 17:05:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15488, instance 2, run: true Mar 02 17:05:23 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:23 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15497, instance 2, run: true Mar 02 17:05:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15504, instance 2, run: true Mar 02 17:05:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15512, instance 2, run: true Mar 02 17:05:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15520, instance 2, run: true Mar 02 17:05:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15527, instance 2, run: true Mar 02 17:05:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15534, instance 2, run: true Mar 02 17:05:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15543, instance 2, run: true Mar 02 17:05:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15550, instance 2, run: true Mar 02 17:05:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15557, instance 2, run: true Mar 02 17:05:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15564, instance 2, run: true Mar 02 17:05:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15571, instance 2, run: true Mar 02 17:05:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15578, instance 2, run: true Mar 02 17:05:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15587, instance 2, run: true Mar 02 17:05:25 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:25 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 21. Mar 02 17:05:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15594, instance 2, run: true Mar 02 17:05:25 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:25 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15602, instance 2, run: true Mar 02 17:05:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15609, instance 2, run: true Mar 02 17:05:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15616, instance 2, run: true Mar 02 17:05:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15625, instance 2, run: true Mar 02 17:05:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15632, instance 2, run: true Mar 02 17:05:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15639, instance 2, run: true Mar 02 17:05:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15646, instance 2, run: true Mar 02 17:05:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15653, instance 2, run: true Mar 02 17:05:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15660, instance 2, run: true Mar 02 17:05:28 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15669, instance 2, run: true Mar 02 17:05:28 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15676, instance 2, run: true Mar 02 17:05:28 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15683, instance 2, run: true Mar 02 17:05:28 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15690, instance 2, run: true Mar 02 17:05:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15699, instance 2, run: true Mar 02 17:05:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15706, instance 2, run: true Mar 02 17:05:29 volumiodk volumio[15595]: Traceback (most recent call last): Mar 02 17:05:29 volumiodk volumio[15595]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Mar 02 17:05:29 volumiodk volumio[15595]: from camilladsp import CamillaConnection Mar 02 17:05:29 volumiodk volumio[15595]: ModuleNotFoundError: No module named 'camilladsp' Mar 02 17:05:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15713, instance 2, run: true Mar 02 17:05:29 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:29 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15720, instance 2, run: true Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15729, instance 2, run: true Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15736, instance 2, run: true Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15743, instance 2, run: true Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15750, instance 2, run: true Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15757, instance 2, run: true Mar 02 17:05:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15764, instance 2, run: true Mar 02 17:05:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15771, instance 2, run: true Mar 02 17:05:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15780, instance 2, run: true Mar 02 17:05:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15787, instance 2, run: true Mar 02 17:05:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15794, instance 2, run: true Mar 02 17:05:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15801, instance 2, run: true Mar 02 17:05:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15808, instance 2, run: true Mar 02 17:05:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15815, instance 2, run: true Mar 02 17:05:32 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:32 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 22. Mar 02 17:05:32 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:32 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15824, instance 2, run: true Mar 02 17:05:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15832, instance 2, run: true Mar 02 17:05:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15839, instance 2, run: true Mar 02 17:05:33 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15846, instance 2, run: true Mar 02 17:05:33 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15854, instance 2, run: true Mar 02 17:05:33 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15863, instance 2, run: true Mar 02 17:05:33 volumiodk volumio[950]: info: Enabling plugin mpdoutput Mar 02 17:05:33 volumiodk volumio[950]: info: Loading plugin "mpdoutput"... Mar 02 17:05:33 volumiodk volumio[950]: info: PLUGIN START: mpdoutput Mar 02 17:05:33 volumiodk volumio[950]: info: Done. Mar 02 17:05:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15870, instance 2, run: true Mar 02 17:05:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15877, instance 2, run: true Mar 02 17:05:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15884, instance 2, run: true Mar 02 17:05:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15893, instance 2, run: true Mar 02 17:05:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15901, instance 2, run: true Mar 02 17:05:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15908, instance 2, run: true Mar 02 17:05:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15915, instance 2, run: true Mar 02 17:05:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15924, instance 2, run: true Mar 02 17:05:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15931, instance 2, run: true Mar 02 17:05:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15938, instance 2, run: true Mar 02 17:05:36 volumiodk volumio[15822]: Traceback (most recent call last): Mar 02 17:05:36 volumiodk volumio[15822]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:05:36 volumiodk volumio[15822]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:05:36 volumiodk volumio[15822]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:05:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15945, instance 2, run: true Mar 02 17:05:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15954, instance 2, run: true Mar 02 17:05:36 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:36 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15961, instance 2, run: true Mar 02 17:05:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15968, instance 2, run: true Mar 02 17:05:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15975, instance 2, run: true Mar 02 17:05:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15982, instance 2, run: true Mar 02 17:05:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15989, instance 2, run: true Mar 02 17:05:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 15996, instance 2, run: true Mar 02 17:05:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16005, instance 2, run: true Mar 02 17:05:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16012, instance 2, run: true Mar 02 17:05:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16019, instance 2, run: true Mar 02 17:05:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16026, instance 2, run: true Mar 02 17:05:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16033, instance 2, run: true Mar 02 17:05:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16040, instance 2, run: true Mar 02 17:05:39 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:39 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 23. Mar 02 17:05:39 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16049, instance 2, run: true Mar 02 17:05:39 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16057, instance 2, run: true Mar 02 17:05:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16064, instance 2, run: true Mar 02 17:05:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16071, instance 2, run: true Mar 02 17:05:40 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16078, instance 2, run: true Mar 02 17:05:40 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Mar 02 17:05:40 volumiodk volumio[950]: info: [1740931540351] [80s80s] handleBrowseUri curUri: 80s80s Mar 02 17:05:40 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16087, instance 2, run: true Mar 02 17:05:40 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:05:40 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16094, instance 2, run: true Mar 02 17:05:41 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16102, instance 2, run: true Mar 02 17:05:41 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16111, instance 2, run: true Mar 02 17:05:41 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16118, instance 2, run: true Mar 02 17:05:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16125, instance 2, run: true Mar 02 17:05:42 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Mar 02 17:05:42 volumiodk volumio[950]: info: [1740931542046] [80s80s] handleBrowseUri curUri: 80s80s/nineties Mar 02 17:05:42 volumiodk volumio[950]: info: [1740931542052] [80s80s] getRadioContent url: nineties Mar 02 17:05:42 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:05:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16132, instance 2, run: true Mar 02 17:05:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16141, instance 2, run: true Mar 02 17:05:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16148, instance 2, run: true Mar 02 17:05:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16155, instance 2, run: true Mar 02 17:05:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16162, instance 2, run: true Mar 02 17:05:43 volumiodk volumio[16050]: Traceback (most recent call last): Mar 02 17:05:43 volumiodk volumio[16050]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:05:43 volumiodk volumio[16050]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:05:43 volumiodk volumio[16050]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:05:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16171, instance 2, run: true Mar 02 17:05:43 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:43 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16178, instance 2, run: true Mar 02 17:05:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16185, instance 2, run: true Mar 02 17:05:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16192, instance 2, run: true Mar 02 17:05:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16199, instance 2, run: true Mar 02 17:05:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16208, instance 2, run: true Mar 02 17:05:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16215, instance 2, run: true Mar 02 17:05:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16222, instance 2, run: true Mar 02 17:05:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16229, instance 2, run: true Mar 02 17:05:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16236, instance 2, run: true Mar 02 17:05:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16243, instance 2, run: true Mar 02 17:05:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16250, instance 2, run: true Mar 02 17:05:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16259, instance 2, run: true Mar 02 17:05:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16266, instance 2, run: true Mar 02 17:05:45 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:45 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 24. Mar 02 17:05:45 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:45 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16274, instance 2, run: true Mar 02 17:05:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16281, instance 2, run: true Mar 02 17:05:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16288, instance 2, run: true Mar 02 17:05:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16297, instance 2, run: true Mar 02 17:05:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16304, instance 2, run: true Mar 02 17:05:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16311, instance 2, run: true Mar 02 17:05:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16318, instance 2, run: true Mar 02 17:05:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16327, instance 2, run: true Mar 02 17:05:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16334, instance 2, run: true Mar 02 17:05:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16341, instance 2, run: true Mar 02 17:05:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16348, instance 2, run: true Mar 02 17:05:49 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16357, instance 2, run: true Mar 02 17:05:49 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16364, instance 2, run: true Mar 02 17:05:49 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16371, instance 2, run: true Mar 02 17:05:49 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16378, instance 2, run: true Mar 02 17:05:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16385, instance 2, run: true Mar 02 17:05:50 volumiodk volumio[16267]: Traceback (most recent call last): Mar 02 17:05:50 volumiodk volumio[16267]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:05:50 volumiodk volumio[16267]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:05:50 volumiodk volumio[16267]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:05:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16394, instance 2, run: true Mar 02 17:05:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16401, instance 2, run: true Mar 02 17:05:50 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:50 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16408, instance 2, run: true Mar 02 17:05:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16415, instance 2, run: true Mar 02 17:05:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16423, instance 2, run: true Mar 02 17:05:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16431, instance 2, run: true Mar 02 17:05:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16441, instance 2, run: true Mar 02 17:05:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16450, instance 2, run: true Mar 02 17:05:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16459, instance 2, run: true Mar 02 17:05:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16467, instance 2, run: true Mar 02 17:05:52 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16474, instance 2, run: true Mar 02 17:05:52 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16481, instance 2, run: true Mar 02 17:05:52 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:05:52 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:05:52 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:05:52 volumiodk volumio[950]: info: Adding Item to queue: webnineties/2 Mar 02 17:05:52 volumiodk volumio[950]: info: Exploding uri webnineties/2 in service 80s80s Mar 02 17:05:52 volumiodk volumio[950]: info: [1740931552353] [80s80s] explodeUri: webnineties/2 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: [1740931552437] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Mar 02 17:05:52 volumiodk volumio[950]: info: [1740931552447] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand stop took 177 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/8c/16/e7/8c16e71f-5a5c-797c-efcf-f0550d576892/source/600x600bb.jpg","name":"David Bowie - Absolute Beginners","title":"Absolute Beginners","artist":"80s80s David Bowie","album":"","streaming":true,"disableUiControls":true,"duration":"320","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:05:52 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:52 volumiodk volumio[950]: info: No code Mar 02 17:05:52 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:52 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:52 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:52 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16490, instance 2, run: true Mar 02 17:05:52 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16497, instance 2, run: true Mar 02 17:05:52 volumiodk volumio[950]: info: [1740931552838] [80s80s] received new event containing 2 songs. Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand stop took 8 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:05:52 volumiodk volumio[950]: info: Mar 02 17:05:52 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:52 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:05:52 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 25. Mar 02 17:05:52 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:52 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand clear took 17 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:05:52 volumiodk volumio[950]: info: Mar 02 17:05:52 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:52 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:52 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:05:52 volumiodk volumio[950]: info: Mar 02 17:05:52 volumiodk volumio[950]: ---------------------------- MPD announces state update: options Mar 02 17:05:52 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:05:52 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:05:52 volumiodk volumio[950]: info: ------------------------------ 42ms Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 36 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:52 volumiodk volumio[950]: info: ------------------------------ 39ms Mar 02 17:05:52 volumiodk volumio[950]: info: [1740931552924] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Mar 02 17:05:52 volumiodk volumio[950]: info: Mar 02 17:05:52 volumiodk volumio[950]: ---------------------------- MPD announces state update: options Mar 02 17:05:52 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:52 volumiodk volumio[950]: info: Mar 02 17:05:52 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:52 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand status took 46 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 40 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: info: sendMpdCommand status took 25 milliseconds Mar 02 17:05:52 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:53 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:05:53 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:53 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:53 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:53 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:05:53 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:53 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:53 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:53 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:53 volumiodk volumio[950]: info: ------------------------------ 178ms Mar 02 17:05:53 volumiodk volumio[950]: info: ------------------------------ 186ms Mar 02 17:05:53 volumiodk volumio[950]: info: Mar 02 17:05:53 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:53 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:53 volumiodk volumio[950]: info: ------------------------------ 207ms Mar 02 17:05:53 volumiodk volumio[950]: info: sendMpdCommand play took 148 milliseconds Mar 02 17:05:53 volumiodk volumio[950]: info: ------------------------------ 32ms Mar 02 17:05:53 volumiodk volumio[950]: info: [1740931553167] [80s80s] Pushing the next song state: Vengaboys - Up & Down Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/4d/c9/63/4dc9637e-279c-4d17-5a07-6357bc778d88/source/600x600bb.jpg","name":"Vengaboys - Up & Down","title":"Up & Down","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"221","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:05:53 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:05:53 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:05:53 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:05:53 volumiodk volumio[950]: info: [1740931553176] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:05:53 volumiodk volumio[950]: info: [1740931553189] [80s80s] Setting timer to: 221000 milliseconds. Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:53 volumiodk volumio[950]: info: CorePlayQueue::getTrack 1 Mar 02 17:05:53 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16511, instance 2, run: true Mar 02 17:05:53 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16524, instance 2, run: true Mar 02 17:05:53 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16532, instance 2, run: true Mar 02 17:05:54 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16542, instance 2, run: true Mar 02 17:05:54 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16549, instance 2, run: true Mar 02 17:05:54 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16558, instance 2, run: true Mar 02 17:05:54 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16565, instance 2, run: true Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16572, instance 2, run: true Mar 02 17:05:55 volumiodk volumio[950]: info: Mar 02 17:05:55 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:55 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:55 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:55 volumiodk volumio[950]: info: Mar 02 17:05:55 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:55 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:55 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp stopping service pid 12389... Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp service terminated, instance 1 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp service started and running in background, instance 1 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp stopping service pid 16572... Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp service terminated, instance 2 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:05:55 volumiodk volumio[950]: info: camilladsp service started and running in background, instance 2 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:05:55 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:05:55 volumiodk volumio[950]: info: Mar 02 17:05:55 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:55 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:55 volumiodk volumio[950]: info: Mar 02 17:05:55 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:55 volumiodk volumio[950]: info: Mar 02 17:05:55 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:56 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:56 volumiodk volumio[950]: info: Mar 02 17:05:56 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand status took 670 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand status took 645 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:56 volumiodk volumio[950]: info: Mar 02 17:05:56 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:56 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:56 volumiodk volumio[950]: info: Mar 02 17:05:56 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:56 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16585, instance 2, run: true Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 350ms Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand status took 317 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 338ms Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand status took 306 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 303 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 291 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 293ms Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand status took 280 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:56 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:56 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:56 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:56 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:56 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:56 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 1061ms Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 1054ms Mar 02 17:05:56 volumiodk volumio[950]: info: Mar 02 17:05:56 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:56 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:56 volumiodk volumio[950]: info: Mar 02 17:05:56 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:56 volumiodk volumio[950]: error: FusionDsp - WebSocket error: [object Object] Mar 02 17:05:56 volumiodk volumio[950]: error: FusionDsp - WebSocket error: [object Object] Mar 02 17:05:56 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16594, instance 1, run: true Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 304 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 304 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 288 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 262ms Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand status took 252 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:56 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:56 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:56 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:56 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:56 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:56 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:56 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:56 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:56 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 780ms Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 767ms Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 715ms Mar 02 17:05:56 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 135 milliseconds Mar 02 17:05:56 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:56 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:56 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:56 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:56 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:56 volumiodk volumio[950]: info: ------------------------------ 449ms Mar 02 17:05:57 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16601, instance 1, run: true Mar 02 17:05:57 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16608, instance 1, run: true Mar 02 17:05:57 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16615, instance 1, run: true Mar 02 17:05:58 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16624, instance 1, run: true Mar 02 17:05:58 volumiodk volumio[16504]: Traceback (most recent call last): Mar 02 17:05:58 volumiodk volumio[16504]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:05:58 volumiodk volumio[16504]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:05:58 volumiodk volumio[16504]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:05:58 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:05:58 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:05:58 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:05:58 volumiodk volumio[950]: info: Adding Item to queue: webnineties/0 Mar 02 17:05:58 volumiodk volumio[950]: info: Exploding uri webnineties/0 in service 80s80s Mar 02 17:05:58 volumiodk volumio[950]: info: [1740931558358] [80s80s] explodeUri: webnineties/0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: [1740931558425] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/pop/mp3-192/volumio Mar 02 17:05:58 volumiodk volumio[950]: info: [1740931558426] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=2 Mar 02 17:05:58 volumiodk volumio[950]: info: Mar 02 17:05:58 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:58 volumiodk volumio[950]: info: sendMpdCommand stop took 139 milliseconds Mar 02 17:05:58 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/4d/c9/63/4dc9637e-279c-4d17-5a07-6357bc778d88/source/600x600bb.jpg","name":"Vengaboys - Up & Down","title":"Up & Down","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"221","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:05:58 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:58 volumiodk volumio[950]: info: No code Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:58 volumiodk volumio[950]: info: Mar 02 17:05:58 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:05:58 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:05:58 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:05:58 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16633, instance 1, run: true Mar 02 17:05:58 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:05:58 volumiodk volumio[950]: info: sendMpdCommand status took 310 milliseconds Mar 02 17:05:58 volumiodk volumio[950]: info: sendMpdCommand status took 173 milliseconds Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:05:58 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 02 17:05:58 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:58 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:05:58 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:58 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:58 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:05:58 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:58 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:05:58 volumiodk volumio[950]: info: ------------------------------ 461ms Mar 02 17:05:58 volumiodk volumio[950]: info: ------------------------------ 330ms Mar 02 17:05:59 volumiodk volumio[950]: info: [1740931559004] [80s80s] received new event containing 2 songs. Mar 02 17:05:59 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:05:59 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16640, instance 1, run: true Mar 02 17:05:59 volumiodk volumio[950]: info: sendMpdCommand stop took 58 milliseconds Mar 02 17:05:59 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:05:59 volumiodk volumio[950]: info: Mar 02 17:05:59 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:59 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:59 volumiodk volumio[950]: info: sendMpdCommand clear took 25 milliseconds Mar 02 17:05:59 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:05:59 volumiodk volumio[950]: info: Mar 02 17:05:59 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:59 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:59 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:05:59 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:05:59 volumiodk volumio[950]: info: ------------------------------ 32ms Mar 02 17:05:59 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 20 milliseconds Mar 02 17:05:59 volumiodk volumio[950]: info: ------------------------------ 15ms Mar 02 17:05:59 volumiodk volumio[950]: info: [1740931559122] [80s80s] adding url: http://streams.90s90s.de/pop/mp3-192/volumio Mar 02 17:05:59 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" Mar 02 17:05:59 volumiodk volumio[950]: info: Mar 02 17:05:59 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:59 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:59 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" took 20 milliseconds Mar 02 17:05:59 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:05:59 volumiodk volumio[950]: info: Mar 02 17:05:59 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:05:59 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:05:59 volumiodk volumio[950]: info: ------------------------------ 43ms Mar 02 17:05:59 volumiodk volumio[950]: info: sendMpdCommand play took 18 milliseconds Mar 02 17:05:59 volumiodk volumio[950]: info: ------------------------------ 16ms Mar 02 17:05:59 volumiodk volumio[950]: info: [1740931559195] [80s80s] Pushing the next song state: Oasis - Wonderwall Mar 02 17:05:59 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:59 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:05:59 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:59 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music113/v4/f1/91/ef/f191effe-4cf2-932b-f2cf-a724454d2d49/source/600x600bb.jpg","name":"Oasis - Wonderwall","title":"Wonderwall","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"246","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:05:59 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:05:59 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:05:59 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:05:59 volumiodk volumio[950]: info: [1740931559210] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:05:59 volumiodk volumio[950]: info: [1740931559217] [80s80s] Setting timer to: 246000 milliseconds. Mar 02 17:05:59 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16649, instance 2, run: true Mar 02 17:05:59 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:05:59 volumiodk volumio[950]: info: CorePlayQueue::getTrack 1 Mar 02 17:05:59 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16656, instance 2, run: true Mar 02 17:05:59 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16663, instance 2, run: true Mar 02 17:05:59 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16670, instance 2, run: true Mar 02 17:06:00 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16677, instance 2, run: true Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: Mar 02 17:06:00 volumiodk volumio[950]: error: FusionDsp - invalid sample rate Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: Mar 02 17:06:00 volumiodk volumio[950]: error: FusionDsp - invalid sample rate Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:00 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:00 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand status took 206 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand status took 200 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand status took 67 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 81ms Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand status took 72 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 65 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 59 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand status took 35 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:00 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:00 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:00 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:00 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:00 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:00 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 367ms Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 342ms Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:00 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:00 volumiodk volumio[950]: info: Mar 02 17:06:00 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:00 volumiodk volumio[950]: error: FusionDsp - WebSocket error: [object Object] Mar 02 17:06:00 volumiodk volumio[950]: error: FusionDsp - WebSocket error: [object Object] Mar 02 17:06:00 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16685, instance 2, run: true Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 165 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 168 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 165 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 119ms Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand status took 105 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:00 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:00 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:00 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:00 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:00 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:00 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:00 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:00 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:00 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 331ms Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 334ms Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 290ms Mar 02 17:06:00 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 65 milliseconds Mar 02 17:06:00 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:00 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:00 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:00 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:00 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:00 volumiodk volumio[950]: info: ------------------------------ 325ms Mar 02 17:06:00 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:06:00 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 26. Mar 02 17:06:00 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:06:00 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:06:01 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16695, instance 2, run: true Mar 02 17:06:01 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Mar 02 17:06:01 volumiodk volumio[950]: info: [1740931561148] [80s80s] handleBrowseUri curUri: 80s80s/eighties Mar 02 17:06:01 volumiodk volumio[950]: info: [1740931561149] [80s80s] getRadioContent url: eighties Mar 02 17:06:01 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:01 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16702, instance 2, run: true Mar 02 17:06:01 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16709, instance 2, run: true Mar 02 17:06:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16718, instance 2, run: true Mar 02 17:06:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16725, instance 2, run: true Mar 02 17:06:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16732, instance 2, run: true Mar 02 17:06:02 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16739, instance 2, run: true Mar 02 17:06:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16748, instance 2, run: true Mar 02 17:06:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16755, instance 2, run: true Mar 02 17:06:03 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16762, instance 2, run: true Mar 02 17:06:03 volumiodk volumio[950]: info: Mar 02 17:06:03 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:03 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:03 volumiodk volumio[950]: info: Mar 02 17:06:03 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:03 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:03 volumiodk volumio[950]: info: Mar 02 17:06:03 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:03 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:03 volumiodk volumio[950]: info: Mar 02 17:06:03 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:03 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:03 volumiodk volumio[950]: info: ------------------------------ 50ms Mar 02 17:06:03 volumiodk volumio[950]: info: sendMpdCommand status took 60 milliseconds Mar 02 17:06:03 volumiodk volumio[950]: info: ------------------------------ 51ms Mar 02 17:06:03 volumiodk volumio[950]: info: sendMpdCommand status took 29 milliseconds Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:03 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 02 17:06:03 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:03 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:03 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:03 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:03 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:03 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:03 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:03 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:03 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:03 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:03 volumiodk volumio[950]: info: ------------------------------ 128ms Mar 02 17:06:03 volumiodk volumio[950]: info: ------------------------------ 107ms Mar 02 17:06:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16769, instance 2, run: true Mar 02 17:06:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16776, instance 2, run: true Mar 02 17:06:04 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16795, instance 2, run: true Mar 02 17:06:05 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16820, instance 2, run: true Mar 02 17:06:05 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16842, instance 2, run: true Mar 02 17:06:06 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16862, instance 2, run: true Mar 02 17:06:06 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16869, instance 2, run: true Mar 02 17:06:06 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:06:06 volumiodk volumio[16694]: Traceback (most recent call last): Mar 02 17:06:06 volumiodk volumio[16694]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:06:06 volumiodk volumio[16694]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:06:06 volumiodk volumio[16694]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:06:06 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:06:06 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:06 volumiodk volumio[950]: info: Adding Item to queue: webeighties/9 Mar 02 17:06:06 volumiodk volumio[950]: info: Using cached record of: webeighties/9 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: [1740931566626] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/100/mp3-192/volumio Mar 02 17:06:06 volumiodk volumio[950]: info: [1740931566628] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=111&count=2 Mar 02 17:06:06 volumiodk volumio[950]: info: Mar 02 17:06:06 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:06 volumiodk volumio[950]: info: sendMpdCommand stop took 205 milliseconds Mar 02 17:06:06 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music113/v4/f1/91/ef/f191effe-4cf2-932b-f2cf-a724454d2d49/source/600x600bb.jpg","name":"Oasis - Wonderwall","title":"Wonderwall","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"246","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:06 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:06 volumiodk volumio[950]: info: No code Mar 02 17:06:06 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:06 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:06 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:06 volumiodk volumio[950]: info: Mar 02 17:06:06 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:06 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:06 volumiodk volumio[950]: info: sendMpdCommand status took 213 milliseconds Mar 02 17:06:06 volumiodk volumio[950]: info: sendMpdCommand status took 71 milliseconds Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:06 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16876, instance 2, run: true Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 175 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 163 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:07 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:07 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:07 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:07 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:07 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:06:07 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:07 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:07 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:07 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:07 volumiodk volumio[950]: info: ------------------------------ 575ms Mar 02 17:06:07 volumiodk volumio[950]: info: ------------------------------ 444ms Mar 02 17:06:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16885, instance 2, run: true Mar 02 17:06:07 volumiodk volumio[950]: info: [1740931567430] [80s80s] received new event containing 2 songs. Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16892, instance 1, run: true Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand stop took 93 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:06:07 volumiodk volumio[950]: info: Mar 02 17:06:07 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:07 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand clear took 26 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:06:07 volumiodk volumio[950]: info: Mar 02 17:06:07 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:07 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:07 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:07 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:07 volumiodk volumio[950]: info: ------------------------------ 33ms Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 27 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: info: ------------------------------ 20ms Mar 02 17:06:07 volumiodk volumio[950]: info: [1740931567584] [80s80s] adding url: http://streams.80s80s.de/100/mp3-192/volumio Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/100/mp3-192/volumio" Mar 02 17:06:07 volumiodk volumio[950]: info: Mar 02 17:06:07 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:07 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.80s80s.de/100/mp3-192/volumio" took 12 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:06:07 volumiodk volumio[950]: info: Mar 02 17:06:07 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:07 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:07 volumiodk volumio[950]: info: ------------------------------ 57ms Mar 02 17:06:07 volumiodk volumio[950]: info: sendMpdCommand play took 26 milliseconds Mar 02 17:06:07 volumiodk volumio[950]: info: ------------------------------ 20ms Mar 02 17:06:07 volumiodk volumio[950]: info: [1740931567656] [80s80s] Pushing the next song state: Prince - Mary Don't You Weep Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","name":"Prince - Mary Don't You Weep","title":"Mary Don't You Weep","artist":"80s80s Prince","album":"","streaming":true,"disableUiControls":true,"duration":"275","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:07 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:07 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:07 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:07 volumiodk volumio[950]: info: [1740931567672] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:06:07 volumiodk volumio[950]: info: [1740931567679] [80s80s] Setting timer to: 275000 milliseconds. Mar 02 17:06:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16900, instance 1, run: true Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:07 volumiodk volumio[950]: info: CorePlayQueue::getTrack 1 Mar 02 17:06:07 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16907, instance 1, run: true Mar 02 17:06:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16914, instance 1, run: true Mar 02 17:06:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16921, instance 1, run: true Mar 02 17:06:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16928, instance 1, run: true Mar 02 17:06:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16936, instance 1, run: true Mar 02 17:06:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16944, instance 1, run: true Mar 02 17:06:08 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16951, instance 1, run: true Mar 02 17:06:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16958, instance 1, run: true Mar 02 17:06:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16965, instance 1, run: true Mar 02 17:06:09 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:06:09 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 27. Mar 02 17:06:09 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 54 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 46 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:09 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:09 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:09 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:09 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:09 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:09 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 143 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 131 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 128 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 115 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:09 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:09 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:09 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:09 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:09 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:09 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 306ms Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 291ms Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:09 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16976, instance 1, run: true Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:09 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 274ms Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 237 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 235 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 228 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 192ms Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 172 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:09 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:09 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:09 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:09 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:09 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:09 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 503ms Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 438ms Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:09 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:09 volumiodk volumio[950]: info: Mar 02 17:06:09 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:09 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 118ms Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 96 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 67 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 59 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: info: ------------------------------ 33ms Mar 02 17:06:09 volumiodk volumio[950]: info: sendMpdCommand status took 25 milliseconds Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:09 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:10 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:10 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:10 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:10 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:10 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:10 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:10 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:10 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:10 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:10 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:10 volumiodk volumio[950]: info: ------------------------------ 393ms Mar 02 17:06:10 volumiodk volumio[950]: info: ------------------------------ 305ms Mar 02 17:06:10 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 53 milliseconds Mar 02 17:06:10 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 55 milliseconds Mar 02 17:06:10 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:10 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:10 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:10 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:10 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:10 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:10 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:10 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:10 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:10 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:10 volumiodk volumio[950]: info: ------------------------------ 268ms Mar 02 17:06:10 volumiodk volumio[950]: info: ------------------------------ 192ms Mar 02 17:06:10 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:10 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:10 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16985, instance 1, run: true Mar 02 17:06:10 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16992, instance 1, run: true Mar 02 17:06:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 16999, instance 1, run: true Mar 02 17:06:11 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 17:06:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17009, instance 1, run: true Mar 02 17:06:11 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17016, instance 1, run: true Mar 02 17:06:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17023, instance 1, run: true Mar 02 17:06:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17030, instance 1, run: true Mar 02 17:06:12 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17039, instance 1, run: true Mar 02 17:06:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17046, instance 1, run: true Mar 02 17:06:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17053, instance 1, run: true Mar 02 17:06:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17060, instance 1, run: true Mar 02 17:06:13 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17069, instance 1, run: true Mar 02 17:06:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17076, instance 1, run: true Mar 02 17:06:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17083, instance 1, run: true Mar 02 17:06:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17090, instance 1, run: true Mar 02 17:06:14 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17099, instance 1, run: true Mar 02 17:06:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17106, instance 1, run: true Mar 02 17:06:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17113, instance 1, run: true Mar 02 17:06:15 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17120, instance 1, run: true Mar 02 17:06:15 volumiodk volumio[16972]: Traceback (most recent call last): Mar 02 17:06:15 volumiodk volumio[16972]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:06:15 volumiodk volumio[16972]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:06:15 volumiodk volumio[16972]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:06:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17129, instance 1, run: true Mar 02 17:06:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17136, instance 1, run: true Mar 02 17:06:16 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:06:16 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:06:16 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17143, instance 1, run: true Mar 02 17:06:16 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:06:16 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:06:16 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:06:16 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:16 volumiodk volumio[950]: info: Adding Item to queue: webeighties/4 Mar 02 17:06:16 volumiodk volumio[950]: info: Using cached record of: webeighties/4 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: [1740931576798] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Mar 02 17:06:16 volumiodk volumio[950]: info: [1740931576799] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Mar 02 17:06:16 volumiodk volumio[950]: info: Mar 02 17:06:16 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:16 volumiodk volumio[950]: info: sendMpdCommand stop took 290 milliseconds Mar 02 17:06:16 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:16 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","name":"Prince - Mary Don't You Weep","title":"Mary Don't You Weep","artist":"80s80s Prince","album":"","streaming":true,"disableUiControls":true,"duration":"275","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:16 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:16 volumiodk volumio[950]: info: No code Mar 02 17:06:16 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:16 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:16 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:17 volumiodk volumio[950]: info: Mar 02 17:06:17 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:17 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand status took 180 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand status took 67 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17150, instance 1, run: true Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 86 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 88 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:17 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:17 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:17 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:17 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:17 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:17 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:17 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:17 volumiodk volumio[950]: info: ------------------------------ 459ms Mar 02 17:06:17 volumiodk volumio[950]: info: ------------------------------ 344ms Mar 02 17:06:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17159, instance 2, run: true Mar 02 17:06:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17160, instance 1, run: true Mar 02 17:06:17 volumiodk volumio[950]: info: [1740931577622] [80s80s] received new event containing 2 songs. Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand stop took 48 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:06:17 volumiodk volumio[950]: info: Mar 02 17:06:17 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:17 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand clear took 9 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:06:17 volumiodk volumio[950]: info: Mar 02 17:06:17 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:17 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:17 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:17 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:17 volumiodk volumio[950]: info: ------------------------------ 24ms Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 18 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: info: ------------------------------ 15ms Mar 02 17:06:17 volumiodk volumio[950]: info: [1740931577713] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Mar 02 17:06:17 volumiodk volumio[950]: info: Mar 02 17:06:17 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:17 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" took 9 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:06:17 volumiodk volumio[950]: info: Mar 02 17:06:17 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:17 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:17 volumiodk volumio[950]: info: ------------------------------ 66ms Mar 02 17:06:17 volumiodk volumio[950]: info: sendMpdCommand play took 36 milliseconds Mar 02 17:06:17 volumiodk volumio[950]: info: ------------------------------ 31ms Mar 02 17:06:17 volumiodk volumio[950]: info: [1740931577794] [80s80s] Pushing the next song state: Heaven 17 - Temptation Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music4/v4/14/84/83/1484834d-ae95-6a1a-4841-f28828408a5c/source/600x600bb.jpg","name":"Heaven 17 - Temptation","title":"Temptation","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"204","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:17 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:17 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:17 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:17 volumiodk volumio[950]: info: [1740931577809] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:06:17 volumiodk volumio[950]: info: [1740931577813] [80s80s] Setting timer to: 204000 milliseconds. Mar 02 17:06:17 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17173, instance 1, run: true Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:17 volumiodk volumio[950]: info: CorePlayQueue::getTrack 1 Mar 02 17:06:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17180, instance 1, run: true Mar 02 17:06:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17187, instance 1, run: true Mar 02 17:06:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17194, instance 1, run: true Mar 02 17:06:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17201, instance 1, run: true Mar 02 17:06:18 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:06:18 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 28. Mar 02 17:06:18 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:06:18 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:06:18 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17209, instance 1, run: true Mar 02 17:06:18 volumiodk volumio[950]: info: Mar 02 17:06:18 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:18 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:18 volumiodk volumio[950]: info: Mar 02 17:06:18 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:18 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:18 volumiodk volumio[950]: info: Mar 02 17:06:18 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:18 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:18 volumiodk volumio[950]: info: sendMpdCommand status took 36 milliseconds Mar 02 17:06:18 volumiodk volumio[950]: info: sendMpdCommand status took 27 milliseconds Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:18 volumiodk volumio[950]: info: Mar 02 17:06:18 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:18 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:18 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:18 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:18 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:18 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:18 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:18 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:18 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:19 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand status took 277 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 261 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 257 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand status took 211 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 457ms Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 465ms Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:19 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17221, instance 1, run: true Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:19 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 387ms Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand status took 347 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 334 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 291 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 238ms Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand status took 216 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 728ms Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 645ms Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:19 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:19 volumiodk volumio[950]: info: Mar 02 17:06:19 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 164ms Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand status took 115 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 86 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 77 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 54ms Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand status took 24 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 561ms Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 411ms Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 89 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 112 milliseconds Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:19 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:19 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:19 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 330ms Mar 02 17:06:19 volumiodk volumio[950]: info: ------------------------------ 219ms Mar 02 17:06:19 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:19 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:19 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17228, instance 1, run: true Mar 02 17:06:20 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17235, instance 1, run: true Mar 02 17:06:20 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:06:20 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:06:20 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:20 volumiodk volumio[950]: info: Adding Item to queue: webeighties/3 Mar 02 17:06:20 volumiodk volumio[950]: info: Using cached record of: webeighties/3 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: [1740931580358] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Mar 02 17:06:20 volumiodk volumio[950]: info: [1740931580360] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Mar 02 17:06:20 volumiodk volumio[950]: info: Mar 02 17:06:20 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:20 volumiodk volumio[950]: info: sendMpdCommand stop took 264 milliseconds Mar 02 17:06:20 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music4/v4/14/84/83/1484834d-ae95-6a1a-4841-f28828408a5c/source/600x600bb.jpg","name":"Heaven 17 - Temptation","title":"Temptation","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"204","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:20 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:20 volumiodk volumio[950]: info: No code Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:20 volumiodk volumio[950]: info: Mar 02 17:06:20 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:20 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:20 volumiodk volumio[950]: info: sendMpdCommand status took 190 milliseconds Mar 02 17:06:20 volumiodk volumio[950]: info: sendMpdCommand status took 49 milliseconds Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:20 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 55 milliseconds Mar 02 17:06:20 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:20 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:20 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:20 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:20 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:20 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:20 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:20 volumiodk volumio[950]: info: ------------------------------ 386ms Mar 02 17:06:20 volumiodk volumio[950]: info: ------------------------------ 232ms Mar 02 17:06:20 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17242, instance 1, run: true Mar 02 17:06:21 volumiodk volumio[950]: info: [1740931581071] [80s80s] received new event containing 2 songs. Mar 02 17:06:21 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:21 volumiodk volumio[950]: info: sendMpdCommand stop took 12 milliseconds Mar 02 17:06:21 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:06:21 volumiodk volumio[950]: info: Mar 02 17:06:21 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:21 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:21 volumiodk volumio[950]: info: sendMpdCommand clear took 34 milliseconds Mar 02 17:06:21 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:06:21 volumiodk volumio[950]: info: Mar 02 17:06:21 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:21 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:21 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:21 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:21 volumiodk volumio[950]: info: ------------------------------ 73ms Mar 02 17:06:21 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 53 milliseconds Mar 02 17:06:21 volumiodk volumio[950]: info: ------------------------------ 44ms Mar 02 17:06:21 volumiodk volumio[950]: info: [1740931581211] [80s80s] adding url: http://streams.80s80s.de/party/mp3-192/volumio Mar 02 17:06:21 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" Mar 02 17:06:21 volumiodk volumio[950]: info: Mar 02 17:06:21 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:21 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:21 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" took 18 milliseconds Mar 02 17:06:21 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:06:21 volumiodk volumio[950]: info: Mar 02 17:06:21 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:21 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:21 volumiodk volumio[950]: info: ------------------------------ 84ms Mar 02 17:06:21 volumiodk volumio[950]: info: sendMpdCommand play took 51 milliseconds Mar 02 17:06:21 volumiodk volumio[950]: info: ------------------------------ 40ms Mar 02 17:06:21 volumiodk volumio[950]: info: [1740931581318] [80s80s] Pushing the next song state: Eurythmics - Love Is a Stranger Mar 02 17:06:21 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:21 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:21 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:21 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/ee/bc/55/eebc5593-3a6d-99c4-8b9c-063692fd3389/source/600x600bb.jpg","name":"Eurythmics - Love Is a Stranger","title":"Love Is a Stranger","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"214","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:21 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:21 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:21 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:21 volumiodk volumio[950]: info: [1740931581343] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:06:21 volumiodk volumio[950]: info: [1740931581348] [80s80s] Setting timer to: 214000 milliseconds. Mar 02 17:06:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17251, instance 1, run: true Mar 02 17:06:21 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:21 volumiodk volumio[950]: info: CorePlayQueue::getTrack 1 Mar 02 17:06:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17258, instance 2, run: true Mar 02 17:06:21 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17265, instance 2, run: true Mar 02 17:06:22 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17272, instance 2, run: true Mar 02 17:06:22 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17280, instance 2, run: true Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:22 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand status took 112 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand status took 110 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:22 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:22 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 76ms Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand status took 66 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 52 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 49 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 47ms Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand status took 37 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:22 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:22 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:22 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:22 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:22 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:22 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 247ms Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 254ms Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:22 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:22 volumiodk volumio[950]: info: Mar 02 17:06:22 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 153ms Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand status took 142 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 123 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 115 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 59ms Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand status took 41 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:22 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:22 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:22 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:22 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:22 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:22 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 271ms Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 241ms Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 114 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 117 milliseconds Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:22 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:22 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:22 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:22 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:22 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:22 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:22 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:22 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 351ms Mar 02 17:06:22 volumiodk volumio[950]: info: ------------------------------ 248ms Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:22 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17291, instance 2, run: true Mar 02 17:06:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17298, instance 2, run: true Mar 02 17:06:23 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17305, instance 2, run: true Mar 02 17:06:23 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:23 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:23 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:23 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:23 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:23 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:23 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:06:23 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:23 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:06:24 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:06:24 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:24 volumiodk volumio[950]: info: Adding Item to queue: webeighties/2 Mar 02 17:06:24 volumiodk volumio[950]: info: Using cached record of: webeighties/2 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: [1740931584215] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Mar 02 17:06:24 volumiodk volumio[950]: info: [1740931584218] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Mar 02 17:06:24 volumiodk volumio[950]: info: Mar 02 17:06:24 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:24 volumiodk volumio[950]: info: sendMpdCommand stop took 280 milliseconds Mar 02 17:06:24 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/ee/bc/55/eebc5593-3a6d-99c4-8b9c-063692fd3389/source/600x600bb.jpg","name":"Eurythmics - Love Is a Stranger","title":"Love Is a Stranger","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"214","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:24 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:24 volumiodk volumio[950]: info: No code Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:24 volumiodk volumio[950]: info: Mar 02 17:06:24 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:24 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:24 volumiodk volumio[950]: info: sendMpdCommand status took 169 milliseconds Mar 02 17:06:24 volumiodk volumio[950]: info: sendMpdCommand status took 52 milliseconds Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:24 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17314, instance 2, run: true Mar 02 17:06:24 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 161 milliseconds Mar 02 17:06:24 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 166 milliseconds Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:24 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:24 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:24 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:24 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:24 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:24 volumiodk volumio[950]: info: ------------------------------ 473ms Mar 02 17:06:24 volumiodk volumio[950]: info: ------------------------------ 349ms Mar 02 17:06:24 volumiodk volumio[950]: info: [1740931584919] [80s80s] received new event containing 2 songs. Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:24 volumiodk volumio[950]: info: sendMpdCommand stop took 18 milliseconds Mar 02 17:06:24 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:06:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17321, instance 2, run: true Mar 02 17:06:25 volumiodk volumio[950]: info: Mar 02 17:06:25 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:25 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:25 volumiodk volumio[950]: info: sendMpdCommand clear took 102 milliseconds Mar 02 17:06:25 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:06:25 volumiodk volumio[950]: info: Mar 02 17:06:25 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:25 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:25 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:25 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:25 volumiodk volumio[950]: info: ------------------------------ 57ms Mar 02 17:06:25 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 51 milliseconds Mar 02 17:06:25 volumiodk volumio[950]: info: ------------------------------ 49ms Mar 02 17:06:25 volumiodk volumio[950]: info: [1740931585115] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Mar 02 17:06:25 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Mar 02 17:06:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17328, instance 1, run: true Mar 02 17:06:25 volumiodk volumio[950]: info: Mar 02 17:06:25 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:25 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:25 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 112 milliseconds Mar 02 17:06:25 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:06:25 volumiodk volumio[950]: info: Mar 02 17:06:25 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:25 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:25 volumiodk volumio[950]: info: ------------------------------ 66ms Mar 02 17:06:25 volumiodk volumio[950]: info: sendMpdCommand play took 39 milliseconds Mar 02 17:06:25 volumiodk volumio[950]: info: ------------------------------ 32ms Mar 02 17:06:25 volumiodk volumio[950]: info: [1740931585303] [80s80s] Pushing the next song state: Foreigner - Waiting for a Girl Like You Mar 02 17:06:25 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:25 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:25 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:25 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music115/v4/3d/a0/6c/3da06cd8-fccc-cebc-e13a-3bae90db4ce5/source/600x600bb.jpg","name":"Foreigner - Waiting for a Girl Like You","title":"Waiting for a Girl Like You","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"267","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:25 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:25 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:25 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:25 volumiodk volumio[950]: info: [1740931585346] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:06:25 volumiodk volumio[950]: info: [1740931585352] [80s80s] Setting timer to: 267000 milliseconds. Mar 02 17:06:25 volumiodk volumio[17208]: Traceback (most recent call last): Mar 02 17:06:25 volumiodk volumio[17208]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:06:25 volumiodk volumio[17208]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:06:25 volumiodk volumio[17208]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:06:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17335, instance 1, run: true Mar 02 17:06:25 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:06:25 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:06:25 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17342, instance 1, run: true Mar 02 17:06:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17351, instance 1, run: true Mar 02 17:06:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17359, instance 1, run: true Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:26 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:26 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand status took 166 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand status took 143 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:26 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 170ms Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand status took 117 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 170ms Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand status took 112 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 99 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 92 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 76ms Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand status took 46 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:26 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:26 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:26 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:26 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:26 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:26 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 340ms Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 316ms Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:26 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:26 volumiodk volumio[950]: info: Mar 02 17:06:26 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 48 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 48 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 44 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 29ms Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand status took 26 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:26 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:26 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:26 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:26 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:26 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:26 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:26 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:26 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:26 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 269ms Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 268ms Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 158ms Mar 02 17:06:26 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17368, instance 1, run: true Mar 02 17:06:26 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 147 milliseconds Mar 02 17:06:26 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:26 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:26 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:26 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:26 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:26 volumiodk volumio[950]: info: ------------------------------ 201ms Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:26 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17377, instance 1, run: true Mar 02 17:06:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17384, instance 1, run: true Mar 02 17:06:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17391, instance 1, run: true Mar 02 17:06:27 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17398, instance 1, run: true Mar 02 17:06:27 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:27 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:06:27 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:06:27 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:27 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:27 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:06:27 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:27 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:27 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:27 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:27 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:27 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:06:28 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:06:28 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:28 volumiodk volumio[950]: info: Adding Item to queue: webeighties/0 Mar 02 17:06:28 volumiodk volumio[950]: info: Using cached record of: webeighties/0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:28 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:06:28 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 29. Mar 02 17:06:28 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:06:28 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: [1740931588206] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Mar 02 17:06:28 volumiodk volumio[950]: info: [1740931588212] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Mar 02 17:06:28 volumiodk volumio[950]: info: Mar 02 17:06:28 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand stop took 225 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music115/v4/3d/a0/6c/3da06cd8-fccc-cebc-e13a-3bae90db4ce5/source/600x600bb.jpg","name":"Foreigner - Waiting for a Girl Like You","title":"Waiting for a Girl Like You","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"267","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:28 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService stop Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:28 volumiodk volumio[950]: info: No code Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:28 volumiodk volumio[950]: info: Mar 02 17:06:28 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:28 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:28 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17406, instance 1, run: true Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand status took 282 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand status took 159 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:28 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:28 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:28 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:28 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:28 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:28 volumiodk volumio[950]: info: ------------------------------ 456ms Mar 02 17:06:28 volumiodk volumio[950]: info: ------------------------------ 316ms Mar 02 17:06:28 volumiodk volumio[950]: info: [1740931588733] [80s80s] received new event containing 2 songs. Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand stop took 9 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:06:28 volumiodk volumio[950]: info: Mar 02 17:06:28 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:28 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand clear took 16 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 02 17:06:28 volumiodk volumio[950]: info: Mar 02 17:06:28 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:28 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:28 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:28 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:28 volumiodk volumio[950]: info: ------------------------------ 63ms Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand consume 1 took 48 milliseconds Mar 02 17:06:28 volumiodk volumio[950]: info: ------------------------------ 47ms Mar 02 17:06:28 volumiodk volumio[950]: info: [1740931588828] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Mar 02 17:06:28 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Mar 02 17:06:28 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17416, instance 1, run: true Mar 02 17:06:28 volumiodk volumio[950]: info: Mar 02 17:06:28 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:28 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:28 volumiodk volumio[950]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 151 milliseconds Mar 02 17:06:29 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:06:29 volumiodk volumio[950]: info: Mar 02 17:06:29 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:29 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:29 volumiodk volumio[950]: info: ------------------------------ 114ms Mar 02 17:06:29 volumiodk volumio[950]: info: sendMpdCommand play took 84 milliseconds Mar 02 17:06:29 volumiodk volumio[950]: info: ------------------------------ 37ms Mar 02 17:06:29 volumiodk volumio[950]: info: [1740931589096] [80s80s] Pushing the next song state: Lisa Stansfield - This Is the Right Time Mar 02 17:06:29 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:29 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:29 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:29 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music/v4/98/e3/4b/98e34b1a-53a1-68d3-bcad-aa1bc096f694/source/600x600bb.jpg","name":"Lisa Stansfield - This Is the Right Time","title":"This Is the Right Time","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"248","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 02 17:06:29 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:29 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:29 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:29 volumiodk volumio[950]: info: [1740931589111] [80s80s] PlayNextTrack API delay: 30 Mar 02 17:06:29 volumiodk volumio[950]: info: [1740931589115] [80s80s] Setting timer to: 248000 milliseconds. Mar 02 17:06:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17423, instance 2, run: true Mar 02 17:06:29 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:29 volumiodk volumio[950]: info: CorePlayQueue::getTrack 1 Mar 02 17:06:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17430, instance 2, run: true Mar 02 17:06:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17437, instance 2, run: true Mar 02 17:06:29 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17444, instance 2, run: true Mar 02 17:06:29 volumiodk volumio[950]: info: Mar 02 17:06:29 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:29 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:29 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:29 volumiodk volumio[950]: info: Mar 02 17:06:29 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:29 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:29 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 201 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 213 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:30 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:30 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 169 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 153 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 132 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 98 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 564ms Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 541ms Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:30 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 269ms Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 237 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 265ms Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 214 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 192 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 155 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 94ms Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 74 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 564ms Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 452ms Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:30 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:30 volumiodk volumio[950]: info: Mar 02 17:06:30 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:30 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17458, instance 2, run: true Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 316 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 312 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 306 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 248ms Mar 02 17:06:30 volumiodk volumio[950]: info: sendMpdCommand status took 233 milliseconds Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:30 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:30 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:30 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:30 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 668ms Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 653ms Mar 02 17:06:30 volumiodk volumio[950]: info: ------------------------------ 459ms Mar 02 17:06:30 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:31 volumiodk volumio[950]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 02 17:06:31 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 70 milliseconds Mar 02 17:06:31 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:31 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:31 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:31 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:31 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 02 17:06:31 volumiodk volumio[950]: info: ------------------------------ 329ms Mar 02 17:06:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17465, instance 2, run: true Mar 02 17:06:31 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17472, instance 2, run: true Mar 02 17:06:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17479, instance 2, run: true Mar 02 17:06:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17488, instance 2, run: true Mar 02 17:06:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17495, instance 2, run: true Mar 02 17:06:32 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17502, instance 2, run: true Mar 02 17:06:33 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17509, instance 2, run: true Mar 02 17:06:33 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17518, instance 2, run: true Mar 02 17:06:33 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17527, instance 2, run: true Mar 02 17:06:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17534, instance 2, run: true Mar 02 17:06:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17543, instance 2, run: true Mar 02 17:06:34 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17550, instance 2, run: true Mar 02 17:06:35 volumiodk volumio[17405]: Traceback (most recent call last): Mar 02 17:06:35 volumiodk volumio[17405]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:06:35 volumiodk volumio[17405]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:06:35 volumiodk volumio[17405]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:06:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17557, instance 2, run: true Mar 02 17:06:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17564, instance 2, run: true Mar 02 17:06:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17571, instance 2, run: true Mar 02 17:06:35 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17580, instance 2, run: true Mar 02 17:06:35 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:06:35 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:06:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17587, instance 2, run: true Mar 02 17:06:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17594, instance 2, run: true Mar 02 17:06:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17601, instance 2, run: true Mar 02 17:06:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17608, instance 2, run: true Mar 02 17:06:36 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17617, instance 2, run: true Mar 02 17:06:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17624, instance 2, run: true Mar 02 17:06:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17631, instance 2, run: true Mar 02 17:06:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17638, instance 2, run: true Mar 02 17:06:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17645, instance 2, run: true Mar 02 17:06:37 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17654, instance 2, run: true Mar 02 17:06:38 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:06:38 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 30. Mar 02 17:06:38 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:06:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17661, instance 2, run: true Mar 02 17:06:38 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:06:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17669, instance 2, run: true Mar 02 17:06:38 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17676, instance 2, run: true Mar 02 17:06:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17685, instance 2, run: true Mar 02 17:06:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17692, instance 2, run: true Mar 02 17:06:39 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17699, instance 2, run: true Mar 02 17:06:40 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17706, instance 2, run: true Mar 02 17:06:40 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17715, instance 2, run: true Mar 02 17:06:40 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17722, instance 2, run: true Mar 02 17:06:41 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 02 17:06:41 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17729, instance 2, run: true Mar 02 17:06:41 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:41 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17738, instance 2, run: true Mar 02 17:06:41 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17745, instance 2, run: true Mar 02 17:06:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17752, instance 2, run: true Mar 02 17:06:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17761, instance 2, run: true Mar 02 17:06:42 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17768, instance 2, run: true Mar 02 17:06:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17775, instance 2, run: true Mar 02 17:06:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17782, instance 2, run: true Mar 02 17:06:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17789, instance 2, run: true Mar 02 17:06:43 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 02 17:06:43 volumiodk volumio[17662]: Traceback (most recent call last): Mar 02 17:06:43 volumiodk volumio[17662]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Mar 02 17:06:43 volumiodk volumio[17662]: from camilladsp_plot.validate_config import CamillaValidator Mar 02 17:06:43 volumiodk volumio[17662]: ModuleNotFoundError: No module named 'camilladsp_plot' Mar 02 17:06:43 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17798, instance 2, run: true Mar 02 17:06:44 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17805, instance 2, run: true Mar 02 17:06:44 volumiodk systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Mar 02 17:06:44 volumiodk systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Mar 02 17:06:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17812, instance 2, run: true Mar 02 17:06:44 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17819, instance 2, run: true Mar 02 17:06:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17826, instance 2, run: true Mar 02 17:06:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17835, instance 2, run: true Mar 02 17:06:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17843, instance 2, run: true Mar 02 17:06:45 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17850, instance 2, run: true Mar 02 17:06:45 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::ClearQueue Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::stPlaybackTimer Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::serviceStop Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::serviceStop Mar 02 17:06:45 volumiodk volumio[950]: info: ControllerMpd::stop Mar 02 17:06:45 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::clearPlayQueue Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::addQueueItems Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::addQueueItems Mar 02 17:06:45 volumiodk volumio[950]: info: Preload queue cleared Mar 02 17:06:45 volumiodk volumio[950]: info: Adding Item to queue: http://stream.antenne.de:80/oldies-but-goldies Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushQueue Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::saveQueue Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::updateTrackBlock Mar 02 17:06:45 volumiodk volumio[950]: info: CorePlayQueue::getTrackBlock Mar 02 17:06:45 volumiodk volumio[950]: info: CoreCommandRouter::volumioPlay Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::play index 0 Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:45 volumiodk volumio[950]: info: CoreStateMachine::stop Mar 02 17:06:46 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:46 volumiodk volumio[950]: info: CoreStateMachine::play index undefined Mar 02 17:06:46 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 17:06:46 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:46 volumiodk volumio[950]: info: CoreStateMachine::startPlaybackTimer Mar 02 17:06:46 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:46 volumiodk volumio[950]: info: [1740931606016] ControllerWebradio::clearAddPlayTrack Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 17:06:46 volumiodk volumio[950]: info: Mar 02 17:06:46 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:46 volumiodk volumio[950]: info: sendMpdCommand stop took 353 milliseconds Mar 02 17:06:46 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:46 volumiodk volumio[950]: info: sendMpdCommand stop took 94 milliseconds Mar 02 17:06:46 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:46 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:46 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:46 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:46 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:46 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:46 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received 80s80s Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 17:06:46 volumiodk volumio[950]: info: Mar 02 17:06:46 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:46 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17857, instance 2, run: true Mar 02 17:06:46 volumiodk volumio[950]: info: Mar 02 17:06:46 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:46 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:46 volumiodk volumio[950]: info: sendMpdCommand status took 136 milliseconds Mar 02 17:06:46 volumiodk volumio[950]: info: sendMpdCommand clear took 117 milliseconds Mar 02 17:06:46 volumiodk volumio[950]: info: sendMpdCommand status took 101 milliseconds Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de:80/oldies-but-goldies" Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:46 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:46 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:46 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:46 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:46 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:46 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:46 volumiodk volumio[950]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 02 17:06:46 volumiodk volumio[950]: info: ------------------------------ 175ms Mar 02 17:06:46 volumiodk volumio[950]: info: Mar 02 17:06:46 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:46 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:46 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:46 volumiodk volumio[950]: info: ------------------------------ 139ms Mar 02 17:06:46 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 124 milliseconds Mar 02 17:06:46 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:46 volumiodk volumio[950]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 02 17:06:46 volumiodk volumio[950]: info: ------------------------------ 274ms Mar 02 17:06:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17865, instance 2, run: true Mar 02 17:06:46 volumiodk systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Mar 02 17:06:46 volumiodk systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 31. Mar 02 17:06:46 volumiodk systemd[1]: Stopped FusionDsp Daemon. Mar 02 17:06:46 volumiodk systemd[1]: Started FusionDsp Daemon. Mar 02 17:06:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17873, instance 2, run: true Mar 02 17:06:46 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17881, instance 1, run: true Mar 02 17:06:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17893, instance 1, run: true Mar 02 17:06:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17900, instance 1, run: true Mar 02 17:06:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17907, instance 1, run: true Mar 02 17:06:47 volumiodk volumio[950]: error: updateQueue error: null Mar 02 17:06:47 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de:80/oldies-but-goldies" Mar 02 17:06:47 volumiodk volumio[950]: info: ------------------------------ 1288ms Mar 02 17:06:47 volumiodk volumio[950]: info: Mar 02 17:06:47 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:47 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:47 volumiodk volumio[950]: info: sendMpdCommand add "http://stream.antenne.de:80/oldies-but-goldies" took 17 milliseconds Mar 02 17:06:47 volumiodk volumio[950]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 02 17:06:47 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand play Mar 02 17:06:47 volumiodk volumio[950]: info: Mar 02 17:06:47 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:47 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:47 volumiodk volumio[950]: info: ------------------------------ 36ms Mar 02 17:06:47 volumiodk volumio[950]: info: sendMpdCommand play took 33 milliseconds Mar 02 17:06:47 volumiodk volumio[950]: info: ------------------------------ 33ms Mar 02 17:06:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17917, instance 1, run: true Mar 02 17:06:47 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17924, instance 1, run: true Mar 02 17:06:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17931, instance 1, run: true Mar 02 17:06:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17938, instance 1, run: true Mar 02 17:06:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17945, instance 1, run: true Mar 02 17:06:48 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17954, instance 1, run: true Mar 02 17:06:48 volumiodk volumio[950]: info: Mar 02 17:06:48 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:48 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:48 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:48 volumiodk volumio[950]: info: Mar 02 17:06:48 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:48 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:48 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand status took 93 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand status took 72 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:49 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:49 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:49 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:49 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 02 17:06:49 volumiodk volumio[950]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 02 17:06:49 volumiodk volumio[950]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:49 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:49 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand status took 159 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 158 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 142 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand status took 49 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:49 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:49 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:49 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus stop Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:49 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:49 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:49 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:49 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:49 volumiodk volumio[950]: info: ------------------------------ 428ms Mar 02 17:06:49 volumiodk volumio[950]: info: ------------------------------ 448ms Mar 02 17:06:49 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17962, instance 1, run: true Mar 02 17:06:49 volumiodk volumio[950]: info: ------------------------------ 392ms Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand status took 378 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: ------------------------------ 384ms Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand status took 364 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 352 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 340 milliseconds Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:49 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:49 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:49 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:49 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:49 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:49 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1676,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:49 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:49 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:49 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:49 volumiodk volumio[950]: info: ------------------------------ 836ms Mar 02 17:06:49 volumiodk volumio[950]: info: ------------------------------ 695ms Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:49 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:49 volumiodk volumio[950]: info: Mar 02 17:06:49 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:49 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:49 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:49 volumiodk volumio[950]: error: FusionDsp - WebSocket error: [object Object] Mar 02 17:06:50 volumiodk volumio[950]: error: FusionDsp - WebSocket error: [object Object] Mar 02 17:06:50 volumiodk volumio[950]: info: Mar 02 17:06:50 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:50 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:50 volumiodk volumio[950]: info: Mar 02 17:06:50 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 434 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 425 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 173ms Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand status took 161 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:50 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:50 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1733,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:50 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:50 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:50 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:50 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1733,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:50 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:50 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 1067ms Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 1072ms Mar 02 17:06:50 volumiodk volumio[950]: info: Mar 02 17:06:50 volumiodk volumio[950]: ---------------------------- MPD announces system playlist update Mar 02 17:06:50 volumiodk volumio[950]: info: Ignoring MPD Status Update Mar 02 17:06:50 volumiodk volumio[950]: info: Mar 02 17:06:50 volumiodk volumio[950]: ---------------------------- MPD announces state update: player Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::getState Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand status Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 272ms Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand status took 250 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 242 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 42ms Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand status took 39 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseState Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:50 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:50 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2488,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:50 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:50 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 524ms Mar 02 17:06:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17971, instance 1, run: true Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 263 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: info: sendMpdCommand playlistinfo took 269 milliseconds Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:50 volumiodk volumio[950]: verbose: ControllerMpd::parseTrackInfo Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:50 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:50 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:50 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:50 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: ControllerMpd::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::servicePushState Mar 02 17:06:50 volumiodk volumio[950]: info: CorePlayQueue::getTrack 0 Mar 02 17:06:50 volumiodk volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blondie - The tide is high (1980)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 02 17:06:50 volumiodk volumio[950]: verbose: CURRENT POSITION 0 Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState stateService play Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::syncState currentStatus play Mar 02 17:06:50 volumiodk volumio[950]: info: Received an update from plugin. extracting info from payload Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreStateMachine::pushState Mar 02 17:06:50 volumiodk volumio[950]: info: CoreCommandRouter::volumioPushState Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 796ms Mar 02 17:06:50 volumiodk volumio[950]: info: ------------------------------ 572ms Mar 02 17:06:50 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17979, instance 1, run: true Mar 02 17:06:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17987, instance 1, run: true Mar 02 17:06:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 17994, instance 1, run: true Mar 02 17:06:51 volumiodk volumio[950]: info: camilladsp spawned new process with pid 18001, instance 1, run: true Mar 02 17:06:52 volumiodk volumio[950]: info: camilladsp spawned new process with pid 18010, instance 1, run: true Mar 02 17:06:52 volumiodk volumio[950]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Mar 02 17:06:52 volumiodk volumio[950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 17:06:52 volumiodk volumio[950]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Mar 02 17:06:52 volumiodk volumio[950]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Mar 02 17:06:52 volumiodk volumio[950]: at onErrorNT (internal/child_process.js:472:16) Mar 02 17:06:52 volumiodk volumio[950]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Mar 02 17:06:52 volumiodk volumio[950]: errno: -13, Mar 02 17:06:52 volumiodk volumio[950]: code: 'EACCES', Mar 02 17:06:52 volumiodk volumio[950]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Mar 02 17:06:52 volumiodk volumio[950]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Mar 02 17:06:52 volumiodk volumio[950]: spawnargs: [ Mar 02 17:06:52 volumiodk volumio[950]: '-p', Mar 02 17:06:52 volumiodk volumio[950]: 9876, Mar 02 17:06:52 volumiodk volumio[950]: '-o', Mar 02 17:06:52 volumiodk volumio[950]: '/tmp/camilladsp.log', Mar 02 17:06:52 volumiodk volumio[950]: '-l', Mar 02 17:06:52 volumiodk volumio[950]: 'warn', Mar 02 17:06:52 volumiodk volumio[950]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Mar 02 17:06:52 volumiodk volumio[950]: ] Mar 02 17:06:52 volumiodk volumio[950]: } Mar 02 17:06:52 volumiodk volumio[950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 17:06:52 volumiodk sudo[18024]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Mar 02 17:06:52 volumiodk sudo[18024]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:06:53 volumiodk sudo[18024]: pam_unix(sudo:session): session closed for user root Mar 02 17:06:53 volumiodk sudo[18027]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Mar 02 17:06:53 volumiodk sudo[18027]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:06:53 volumiodk sudo[18027]: pam_unix(sudo:session): session closed for user root Mar 02 17:06:53 volumiodk sudo[18029]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Mar 02 17:06:53 volumiodk sudo[18029]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 17:06:56 volumiodk sudo[18041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 17:05 Mar 02 17:06:56 volumiodk sudo[18041]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Sep 2024 09:45:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="bananapim1" VOLUMIO_DEVICENAME="BananaPi M1" VOLUMIO_HASH="89d82fddc741ef0aa74780c3c7a7d849"