-- Logs begin at Thu 2019-02-14 10:11:58 WET, end at Sat 2024-04-20 13:16:27 WEST. -- Apr 20 13:15:00 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:00 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:00 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:00 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000185036 s, 334 MB/s Apr 20 13:15:00 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 7976, instance 1, run: true Apr 20 13:15:01 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:01 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:01 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:01 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.00018387 s, 356 MB/s Apr 20 13:15:01 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 7981, instance 1, run: true Apr 20 13:15:02 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:02 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:02 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:02 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000178962 s, 366 MB/s Apr 20 13:15:02 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 7986, instance 1, run: true Apr 20 13:15:03 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:03 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:03 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:03 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000184351 s, 335 MB/s Apr 20 13:15:03 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 7991, instance 1, run: true Apr 20 13:15:04 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:04 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:04 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:04 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000186999 s, 350 MB/s Apr 20 13:15:04 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 7996, instance 1, run: true Apr 20 13:15:05 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:05 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:05 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:05 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000181759 s, 340 MB/s Apr 20 13:15:05 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8003, instance 1, run: true Apr 20 13:15:06 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:06 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:06 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:06 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000206165 s, 318 MB/s Apr 20 13:15:06 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8008, instance 1, run: true Apr 20 13:15:07 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:07 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:07 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:07 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000186592 s, 351 MB/s Apr 20 13:15:07 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8014, instance 1, run: true Apr 20 13:15:08 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:08 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:08 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:08 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000186203 s, 331 MB/s Apr 20 13:15:08 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8019, instance 1, run: true Apr 20 13:15:09 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:09 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:09 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:09 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000196629 s, 333 MB/s Apr 20 13:15:09 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8024, instance 1, run: true Apr 20 13:15:10 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:10 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:10 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:10 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000186259 s, 352 MB/s Apr 20 13:15:10 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8029, instance 1, run: true Apr 20 13:15:11 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:11 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:11 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:11 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000181055 s, 341 MB/s Apr 20 13:15:11 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8034, instance 1, run: true Apr 20 13:15:12 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:12 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:12 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:12 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000186443 s, 352 MB/s Apr 20 13:15:12 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8040, instance 1, run: true Apr 20 13:15:13 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:13 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:13 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:13 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000190777 s, 344 MB/s Apr 20 13:15:13 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8045, instance 1, run: true Apr 20 13:15:14 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:14 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:14 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:14 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.00017724 s, 348 MB/s Apr 20 13:15:14 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8050, instance 1, run: true Apr 20 13:15:15 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:15 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:15 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:15 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000233462 s, 281 MB/s Apr 20 13:15:15 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8055, instance 1, run: true Apr 20 13:15:16 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:16 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:16 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:16 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000177666 s, 347 MB/s Apr 20 13:15:16 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8060, instance 1, run: true Apr 20 13:15:17 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:17 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:17 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:17 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000187443 s, 350 MB/s Apr 20 13:15:18 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8066, instance 1, run: true Apr 20 13:15:19 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:19 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:19 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:19 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000177925 s, 368 MB/s Apr 20 13:15:19 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8071, instance 1, run: true Apr 20 13:15:20 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:20 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:20 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:20 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000180332 s, 342 MB/s Apr 20 13:15:20 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8076, instance 1, run: true Apr 20 13:15:21 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:21 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:21 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:21 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000184814 s, 355 MB/s Apr 20 13:15:21 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8081, instance 1, run: true Apr 20 13:15:22 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:22 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:22 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:22 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000193369 s, 339 MB/s Apr 20 13:15:22 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8086, instance 1, run: true Apr 20 13:15:23 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:23 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:23 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:23 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000182832 s, 338 MB/s Apr 20 13:15:23 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8091, instance 1, run: true Apr 20 13:15:24 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:24 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:24 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:24 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000181017 s, 362 MB/s Apr 20 13:15:24 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8097, instance 1, run: true Apr 20 13:15:25 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:25 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:25 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:25 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000180074 s, 364 MB/s Apr 20 13:15:25 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8104, instance 1, run: true Apr 20 13:15:26 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:26 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:26 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:26 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000181406 s, 340 MB/s Apr 20 13:15:26 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8109, instance 1, run: true Apr 20 13:15:27 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:27 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:27 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:27 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000235777 s, 278 MB/s Apr 20 13:15:27 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8114, instance 1, run: true Apr 20 13:15:28 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:28 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:28 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:28 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000179369 s, 344 MB/s Apr 20 13:15:28 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8119, instance 1, run: true Apr 20 13:15:29 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:29 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:29 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:29 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000194036 s, 338 MB/s Apr 20 13:15:29 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8124, instance 1, run: true Apr 20 13:15:30 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:30 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:30 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:30 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000187888 s, 349 MB/s Apr 20 13:15:30 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8130, instance 1, run: true Apr 20 13:15:31 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:31 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:31 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:31 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000186684 s, 331 MB/s Apr 20 13:15:31 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8135, instance 1, run: true Apr 20 13:15:32 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:32 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:32 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:32 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000210518 s, 311 MB/s Apr 20 13:15:32 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8141, instance 1, run: true Apr 20 13:15:33 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:33 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:33 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:33 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000197573 s, 332 MB/s Apr 20 13:15:33 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8146, instance 1, run: true Apr 20 13:15:34 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:34 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:34 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:34 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000188036 s, 328 MB/s Apr 20 13:15:34 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8151, instance 1, run: true Apr 20 13:15:35 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:35 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:35 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:35 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000198554 s, 330 MB/s Apr 20 13:15:35 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8156, instance 1, run: true Apr 20 13:15:36 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:36 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:36 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:36 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000197147 s, 332 MB/s Apr 20 13:15:36 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8161, instance 1, run: true Apr 20 13:15:37 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:37 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:37 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:37 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000186647 s, 331 MB/s Apr 20 13:15:37 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8166, instance 1, run: true Apr 20 13:15:38 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:38 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:38 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:38 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000185018 s, 354 MB/s Apr 20 13:15:38 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8171, instance 1, run: true Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::volumioNext Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::next Apr 20 13:15:39 volumiorasp volumio[973]: info: [1713615339511] ControllerQobuz::next Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::next Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::stop Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::stPlaybackTimer Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::updateTrackBlock Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrackBlock Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 12 Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:39 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:39 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::serviceStop Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 12 Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::serviceStop Apr 20 13:15:39 volumiorasp volumio[973]: info: [1713615339517] ControllerQobuz::stop Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 20 13:15:39 volumiorasp volumio[973]: info: ControllerMpd::stop Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Apr 20 13:15:39 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:39 volumiorasp volumio[973]: info: Apr 20 13:15:39 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:15:39 volumiorasp volumio[973]: info: sendMpdCommand stop took 9 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::play index undefined Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::startPlaybackTimer Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:39 volumiorasp volumio[973]: info: [1713615339528] ControllerQobuz::clearAddPlayTrack Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::updateTrackBlock Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrackBlock Apr 20 13:15:39 volumiorasp volumio[973]: info: sendMpdCommand status took 3 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: info: sendMpdCommand stop took 2 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand clear Apr 20 13:15:39 volumiorasp volumio[973]: info: Apr 20 13:15:39 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:15:39 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:15:39 volumiorasp volumio[973]: info: Apr 20 13:15:39 volumiorasp volumio[973]: ---------------------------- MPD announces system playlist update Apr 20 13:15:39 volumiorasp volumio[973]: info: Ignoring MPD Status Update Apr 20 13:15:39 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: info: sendMpdCommand clear took 1 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: info: sendMpdCommand status took 1 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/179561400" Apr 20 13:15:39 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:15:39 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:39 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:39 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:39 volumiorasp volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 20 13:15:39 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:39 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:39 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:39 volumiorasp volumio[973]: STREAMING PROXY: Handling url /?data=qobuz://song/179561400 Apr 20 13:15:39 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:39 volumiorasp volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 20 13:15:39 volumiorasp volumio[973]: info: ------------------------------ 12ms Apr 20 13:15:39 volumiorasp volumio[973]: info: ------------------------------ 9ms Apr 20 13:15:39 volumiorasp volumio[973]: info: Apr 20 13:15:39 volumiorasp volumio[973]: ---------------------------- MPD announces system playlist update Apr 20 13:15:39 volumiorasp volumio[973]: info: Ignoring MPD Status Update Apr 20 13:15:39 volumiorasp volumio[973]: error: updateQueue error: null Apr 20 13:15:39 volumiorasp volumio[973]: info: ------------------------------ 12ms Apr 20 13:15:39 volumiorasp volumio[973]: info: Executing endpoint getStreamUrlqobuz Apr 20 13:15:39 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 20 13:15:39 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:39 volumiorasp volumio[973]: 0+0 records in Apr 20 13:15:39 volumiorasp volumio[973]: 0+0 records out Apr 20 13:15:39 volumiorasp volumio[973]: 0 bytes copied, 5.8e-05 s, 0.0 kB/s Apr 20 13:15:39 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8176, instance 1, run: true Apr 20 13:15:39 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:39 volumiorasp volumio[973]: info: getStreamUrl took 442 milliseconds Apr 20 13:15:39 volumiorasp volumio[973]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=179561400&fmt=7&profile=raw&app_id=539451548&cid=2138741&etsp=1713618939&hmac=r7opEezjiSpA-Ppoxvpq2gfMImc Apr 20 13:15:40 volumiorasp volumio[973]: 0+0 records in Apr 20 13:15:40 volumiorasp volumio[973]: 0+0 records out Apr 20 13:15:40 volumiorasp volumio[973]: 0 bytes copied, 6.4129e-05 s, 0.0 kB/s Apr 20 13:15:40 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8182, instance 1, run: true Apr 20 13:15:40 volumiorasp volumio[973]: STREAMING PROXY: Response: 200, length: 28908984 Apr 20 13:15:40 volumiorasp volumio[973]: STREAMING PROXY: Client dropped request, destroying Apr 20 13:15:40 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/179561400" Apr 20 13:15:40 volumiorasp volumio[973]: error: updateQueue error: null Apr 20 13:15:40 volumiorasp volumio[973]: info: Apr 20 13:15:40 volumiorasp volumio[973]: ---------------------------- MPD announces system playlist update Apr 20 13:15:40 volumiorasp volumio[973]: info: Ignoring MPD Status Update Apr 20 13:15:40 volumiorasp volumio[973]: info: ------------------------------ 1213ms Apr 20 13:15:40 volumiorasp volumio[973]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/179561400" took 1 milliseconds Apr 20 13:15:40 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 20 13:15:40 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand play Apr 20 13:15:40 volumiorasp volumio[973]: info: Apr 20 13:15:40 volumiorasp volumio[973]: ---------------------------- MPD announces system playlist update Apr 20 13:15:40 volumiorasp volumio[973]: info: Ignoring MPD Status Update Apr 20 13:15:40 volumiorasp volumio[973]: STREAMING PROXY: Handling url /?data=qobuz://song/179561400 Apr 20 13:15:40 volumiorasp volumio[973]: info: ------------------------------ 3ms Apr 20 13:15:40 volumiorasp volumio[973]: info: sendMpdCommand play took 2 milliseconds Apr 20 13:15:40 volumiorasp volumio[973]: info: ------------------------------ 2ms Apr 20 13:15:40 volumiorasp volumio[973]: info: Executing endpoint getStreamUrlqobuz Apr 20 13:15:40 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 20 13:15:41 volumiorasp volumio[973]: info: getStreamUrl took 446 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2386042&eid=179561400&fmt=7&profile=raw&app_id=539451548&cid=2138741&etsp=1713618941&hmac=dP3VbUiBnW7dxJXQZX6XEoU2KEM Apr 20 13:15:41 volumiorasp volumio[973]: STREAMING PROXY: Response: 200, length: 28908984 Apr 20 13:15:41 volumiorasp volumio[973]: info: Apr 20 13:15:41 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:15:41 volumiorasp volumio[973]: info: Apr 20 13:15:41 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:15:41 volumiorasp volumio[973]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 20 13:15:41 volumiorasp volumio[973]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 20 13:15:41 volumiorasp volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 20 13:15:41 volumiorasp volumio[973]: info: Apr 20 13:15:41 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand status took 9 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand status took 8 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:15:41 volumiorasp volumio[973]: info: Apr 20 13:15:41 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand status took 2 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand status took 1 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:41 volumiorasp volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":150,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"179561400","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/179561400","trackType":"qobuz"} Apr 20 13:15:41 volumiorasp volumio[973]: verbose: CURRENT POSITION 6 Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState stateService play Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState currentStatus stop Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:41 volumiorasp volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":150,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"179561400","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/179561400","trackType":"qobuz"} Apr 20 13:15:41 volumiorasp volumio[973]: verbose: CURRENT POSITION 6 Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState stateService play Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState currentStatus play Apr 20 13:15:41 volumiorasp volumio[973]: info: Received an update from plugin. extracting info from payload Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:41 volumiorasp volumio[973]: info: ------------------------------ 17ms Apr 20 13:15:41 volumiorasp volumio[973]: info: ------------------------------ 23ms Apr 20 13:15:41 volumiorasp volumio[973]: error: FusionDsp - WebSocket error: [object Object] Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:15:41 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:41 volumiorasp volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":150,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"179561400","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/179561400","trackType":"qobuz"} Apr 20 13:15:41 volumiorasp volumio[973]: verbose: CURRENT POSITION 6 Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState stateService play Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState currentStatus play Apr 20 13:15:41 volumiorasp volumio[973]: info: Received an update from plugin. extracting info from payload Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:41 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:15:41 volumiorasp volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":150,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"179561400","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/179561400","trackType":"qobuz"} Apr 20 13:15:41 volumiorasp volumio[973]: verbose: CURRENT POSITION 6 Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState stateService play Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::syncState currentStatus play Apr 20 13:15:41 volumiorasp volumio[973]: info: Received an update from plugin. extracting info from payload Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:15:41 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:15:41 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:15:41 volumiorasp volumio[973]: info: ------------------------------ 26ms Apr 20 13:15:41 volumiorasp volumio[973]: info: ------------------------------ 25ms Apr 20 13:15:41 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:41 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:41 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:41 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:41 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:41 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:15:41 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:41 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:41 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:41 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000308258 s, 213 MB/s Apr 20 13:15:41 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8190, instance 1, run: true Apr 20 13:15:42 volumiorasp volumio[973]: STREAMING PROXY: Client dropped request, destroying Apr 20 13:15:42 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:42 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:42 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:42 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000187906 s, 349 MB/s Apr 20 13:15:42 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8195, instance 1, run: true Apr 20 13:15:43 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:43 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:43 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:43 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000190147 s, 325 MB/s Apr 20 13:15:43 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8200, instance 1, run: true Apr 20 13:15:44 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:44 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:44 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:44 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000183203 s, 358 MB/s Apr 20 13:15:44 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8207, instance 1, run: true Apr 20 13:15:45 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:45 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:45 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:45 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000182036 s, 360 MB/s Apr 20 13:15:45 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8213, instance 1, run: true Apr 20 13:15:46 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:46 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:46 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:46 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000194017 s, 318 MB/s Apr 20 13:15:46 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8218, instance 1, run: true Apr 20 13:15:47 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:47 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:47 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:47 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000185221 s, 354 MB/s Apr 20 13:15:47 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8264, instance 1, run: true Apr 20 13:15:48 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:48 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:48 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:48 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000180073 s, 364 MB/s Apr 20 13:15:48 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8269, instance 1, run: true Apr 20 13:15:49 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:49 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:49 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:49 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000173796 s, 355 MB/s Apr 20 13:15:49 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8274, instance 1, run: true Apr 20 13:15:50 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:50 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:50 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:50 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000184018 s, 356 MB/s Apr 20 13:15:50 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8279, instance 1, run: true Apr 20 13:15:51 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:51 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:51 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:51 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000185851 s, 332 MB/s Apr 20 13:15:51 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8284, instance 1, run: true Apr 20 13:15:52 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:52 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:52 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:52 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.00017711 s, 370 MB/s Apr 20 13:15:52 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8290, instance 1, run: true Apr 20 13:15:53 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:53 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:53 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:53 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000190555 s, 344 MB/s Apr 20 13:15:53 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8295, instance 1, run: true Apr 20 13:15:54 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:54 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:54 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:54 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000175777 s, 351 MB/s Apr 20 13:15:54 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8300, instance 1, run: true Apr 20 13:15:55 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 13:15:55 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 13:15:55 volumiorasp volumio[973]: info: Discovery: Getting this device information Apr 20 13:15:55 volumiorasp volumio[973]: info: CoreCommandRouter::volumioGetState Apr 20 13:15:55 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 13:15:56 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:56 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:56 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:56 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000178388 s, 367 MB/s Apr 20 13:15:56 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8305, instance 1, run: true Apr 20 13:15:57 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:57 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:57 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:57 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000188221 s, 348 MB/s Apr 20 13:15:57 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8310, instance 1, run: true Apr 20 13:15:58 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:58 volumiorasp volumio[973]: 1+1 records in Apr 20 13:15:58 volumiorasp volumio[973]: 1+1 records out Apr 20 13:15:58 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000184554 s, 334 MB/s Apr 20 13:15:58 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8315, instance 1, run: true Apr 20 13:15:58 volumiorasp volumio[973]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 13:15:58 volumiorasp volumio[973]: info: FusionDsp - IP adress is ---------------------------192.168.100.125 Apr 20 13:15:59 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:15:59 volumiorasp volumio[973]: 2+0 records in Apr 20 13:15:59 volumiorasp volumio[973]: 2+0 records out Apr 20 13:15:59 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000183499 s, 357 MB/s Apr 20 13:15:59 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8320, instance 1, run: true Apr 20 13:16:00 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:16:00 volumiorasp volumio[973]: 2+0 records in Apr 20 13:16:00 volumiorasp volumio[973]: 2+0 records out Apr 20 13:16:00 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000188517 s, 348 MB/s Apr 20 13:16:00 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8325, instance 1, run: true Apr 20 13:16:01 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:16:01 volumiorasp volumio[973]: 1+1 records in Apr 20 13:16:01 volumiorasp volumio[973]: 1+1 records out Apr 20 13:16:01 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000179647 s, 344 MB/s Apr 20 13:16:01 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8330, instance 1, run: true Apr 20 13:16:02 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:16:02 volumiorasp volumio[973]: 2+0 records in Apr 20 13:16:02 volumiorasp volumio[973]: 2+0 records out Apr 20 13:16:02 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000207092 s, 316 MB/s Apr 20 13:16:02 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8335, instance 1, run: true Apr 20 13:16:03 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:16:03 volumiorasp volumio[973]: 1+1 records in Apr 20 13:16:03 volumiorasp volumio[973]: 1+1 records out Apr 20 13:16:03 volumiorasp volumio[973]: 61712 bytes (62 kB, 60 KiB) copied, 0.000179203 s, 344 MB/s Apr 20 13:16:03 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8340, instance 1, run: true Apr 20 13:16:04 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:16:04 volumiorasp volumio[973]: 2+0 records in Apr 20 13:16:04 volumiorasp volumio[973]: 2+0 records out Apr 20 13:16:04 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000179147 s, 366 MB/s Apr 20 13:16:04 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8347, instance 1, run: true Apr 20 13:16:05 volumiorasp volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 20 13:16:05 volumiorasp volumio[973]: 2+0 records in Apr 20 13:16:05 volumiorasp volumio[973]: 2+0 records out Apr 20 13:16:05 volumiorasp volumio[973]: 65536 bytes (66 kB, 64 KiB) copied, 0.000187758 s, 349 MB/s Apr 20 13:16:05 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8352, instance 1, run: true Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::volumioNext Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::next Apr 20 13:16:05 volumiorasp volumio[973]: info: [1713615365402] ControllerQobuz::next Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::next Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::stop Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::stPlaybackTimer Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::updateTrackBlock Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrackBlock Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:16:05 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:16:05 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::serviceStop Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 6 Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::serviceStop Apr 20 13:16:05 volumiorasp volumio[973]: info: [1713615365405] ControllerQobuz::stop Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 20 13:16:05 volumiorasp volumio[973]: info: ControllerMpd::stop Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Apr 20 13:16:05 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:16:05 volumiorasp volumio[973]: info: Apr 20 13:16:05 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:16:05 volumiorasp volumio[973]: info: sendMpdCommand stop took 8 milliseconds Apr 20 13:16:05 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::play index undefined Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 18 Apr 20 13:16:05 volumiorasp volumio[973]: info: sendMpdCommand status took 3 milliseconds Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:16:05 volumiorasp volumio[973]: info: Apr 20 13:16:05 volumiorasp volumio[973]: ---------------------------- MPD announces state update: player Apr 20 13:16:05 volumiorasp volumio[973]: info: ControllerMpd::getState Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand status Apr 20 13:16:05 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 20 13:16:05 volumiorasp volumio[973]: info: sendMpdCommand status took 1 milliseconds Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::parseState Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 20 13:16:05 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 0 Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:16:05 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:16:05 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 0 Apr 20 13:16:05 volumiorasp volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 20 13:16:05 volumiorasp volumio[973]: info: ------------------------------ 8ms Apr 20 13:16:05 volumiorasp volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 20 13:16:05 volumiorasp volumio[973]: verbose: ControllerMpd::parseTrackInfo Apr 20 13:16:05 volumiorasp volumio[973]: info: ControllerMpd::pushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::servicePushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreStateMachine::pushState Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 0 Apr 20 13:16:05 volumiorasp volumio[973]: info: CoreCommandRouter::volumioPushState Apr 20 13:16:05 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output update for this device Apr 20 13:16:05 volumiorasp volumio[973]: info: MRS: Pushing multiroomSync output Apr 20 13:16:05 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 0 Apr 20 13:16:05 volumiorasp volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 20 13:16:05 volumiorasp volumio[973]: info: ------------------------------ 8ms Apr 20 13:16:05 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:16:05 volumiorasp volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 20 13:16:06 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:06 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:06 volumiorasp volumio[973]: 0 bytes copied, 6.4703e-05 s, 0.0 kB/s Apr 20 13:16:06 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8357, instance 1, run: true Apr 20 13:16:07 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:07 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:07 volumiorasp volumio[973]: 0 bytes copied, 6.4685e-05 s, 0.0 kB/s Apr 20 13:16:07 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8362, instance 1, run: true Apr 20 13:16:08 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:08 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:08 volumiorasp volumio[973]: 0 bytes copied, 6.574e-05 s, 0.0 kB/s Apr 20 13:16:08 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8367, instance 1, run: true Apr 20 13:16:09 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:09 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:09 volumiorasp volumio[973]: 0 bytes copied, 6.287e-05 s, 0.0 kB/s Apr 20 13:16:09 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8372, instance 1, run: true Apr 20 13:16:10 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:10 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:10 volumiorasp volumio[973]: 0 bytes copied, 6.5796e-05 s, 0.0 kB/s Apr 20 13:16:10 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8377, instance 1, run: true Apr 20 13:16:11 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:11 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:11 volumiorasp volumio[973]: 0 bytes copied, 6.5426e-05 s, 0.0 kB/s Apr 20 13:16:11 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8382, instance 1, run: true Apr 20 13:16:12 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:12 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:12 volumiorasp volumio[973]: 0 bytes copied, 6.9018e-05 s, 0.0 kB/s Apr 20 13:16:12 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8388, instance 1, run: true Apr 20 13:16:13 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:13 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:13 volumiorasp volumio[973]: 0 bytes copied, 6.5999e-05 s, 0.0 kB/s Apr 20 13:16:13 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8393, instance 1, run: true Apr 20 13:16:14 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:14 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:14 volumiorasp volumio[973]: 0 bytes copied, 6.3111e-05 s, 0.0 kB/s Apr 20 13:16:14 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8398, instance 1, run: true Apr 20 13:16:15 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:15 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:15 volumiorasp volumio[973]: 0 bytes copied, 6.0833e-05 s, 0.0 kB/s Apr 20 13:16:15 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8403, instance 1, run: true Apr 20 13:16:16 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:16 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:16 volumiorasp volumio[973]: 0 bytes copied, 6.1833e-05 s, 0.0 kB/s Apr 20 13:16:16 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8409, instance 1, run: true Apr 20 13:16:16 volumiorasp volumio[973]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Apr 20 13:16:16 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Apr 20 13:16:16 volumiorasp volumio[973]: info: FusionDsp - Launching CamillaDsp GUI Apr 20 13:16:16 volumiorasp volumio[973]: info: FusionDsp loaded Apr 20 13:16:16 volumiorasp sudo[8413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 20 13:16:16 volumiorasp sudo[8413]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:16:16 volumiorasp sudo[8413]: pam_unix(sudo:session): session closed for user root Apr 20 13:16:16 volumiorasp volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 20 13:16:16 volumiorasp volumio[973]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 13:16:16 volumiorasp volumio[973]: info: CoreCommandRouter::Close All Modals sent Apr 20 13:16:16 volumiorasp volumio[973]: error: FusionDsp - WebSocket error: [object Object] Apr 20 13:16:16 volumiorasp volumio[973]: info: FusionDsp - IP adress is ---------------------------192.168.100.125 Apr 20 13:16:17 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:17 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:17 volumiorasp volumio[973]: 0 bytes copied, 6.1185e-05 s, 0.0 kB/s Apr 20 13:16:17 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8417, instance 1, run: true Apr 20 13:16:18 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:18 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:18 volumiorasp volumio[973]: 0 bytes copied, 6.2574e-05 s, 0.0 kB/s Apr 20 13:16:18 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8422, instance 1, run: true Apr 20 13:16:19 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:19 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:19 volumiorasp volumio[973]: 0 bytes copied, 7.0573e-05 s, 0.0 kB/s Apr 20 13:16:19 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8427, instance 1, run: true Apr 20 13:16:20 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:20 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:20 volumiorasp volumio[973]: 0 bytes copied, 6.4685e-05 s, 0.0 kB/s Apr 20 13:16:20 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8432, instance 1, run: true Apr 20 13:16:21 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:21 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:21 volumiorasp volumio[973]: 0 bytes copied, 6.5611e-05 s, 0.0 kB/s Apr 20 13:16:21 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8438, instance 1, run: true Apr 20 13:16:22 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:22 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:22 volumiorasp volumio[973]: 0 bytes copied, 6.4463e-05 s, 0.0 kB/s Apr 20 13:16:22 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8443, instance 1, run: true Apr 20 13:16:22 volumiorasp volumio[973]: info: CoreCommandRouter::volumioGetState Apr 20 13:16:22 volumiorasp volumio[973]: info: CorePlayQueue::getTrack 0 Apr 20 13:16:23 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:23 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:23 volumiorasp volumio[973]: 0 bytes copied, 0.000121777 s, 0.0 kB/s Apr 20 13:16:23 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8450, instance 1, run: true Apr 20 13:16:24 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:24 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:24 volumiorasp volumio[973]: 0 bytes copied, 5.9852e-05 s, 0.0 kB/s Apr 20 13:16:24 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8455, instance 1, run: true Apr 20 13:16:24 volumiorasp volumio[973]: info: Executing endpoint metavolumio Apr 20 13:16:24 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 20 13:16:24 volumiorasp volumio[973]: info: Executing endpoint metavolumio Apr 20 13:16:24 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 20 13:16:24 volumiorasp volumio[973]: info: Executing endpoint metavolumio Apr 20 13:16:24 volumiorasp volumio[973]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 20 13:16:25 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:25 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:25 volumiorasp volumio[973]: 0 bytes copied, 0.000179258 s, 0.0 kB/s Apr 20 13:16:25 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8460, instance 1, run: true Apr 20 13:16:26 volumiorasp volumio[973]: 0+0 records in Apr 20 13:16:26 volumiorasp volumio[973]: 0+0 records out Apr 20 13:16:26 volumiorasp volumio[973]: 0 bytes copied, 6.2536e-05 s, 0.0 kB/s Apr 20 13:16:26 volumiorasp volumio[973]: info: camilladsp spawned new process with pid 8465, instance 1, run: true Apr 20 13:16:27 volumiorasp volumio[973]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Apr 20 13:16:27 volumiorasp volumio[973]: info: PLUGIN onReboot : networkfs Apr 20 13:16:27 volumiorasp volumio[973]: info: PLUGIN onReboot : audiophonicsonoff Apr 20 13:16:27 volumiorasp volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:16:27 volumiorasp volumio[973]: TypeError: Cannot read property 'writeSync' of undefined Apr 20 13:16:27 volumiorasp volumio[973]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Apr 20 13:16:27 volumiorasp volumio[973]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Apr 20 13:16:27 volumiorasp volumio[973]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Apr 20 13:16:27 volumiorasp volumio[973]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 20 13:16:27 volumiorasp volumio[973]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 20 13:16:27 volumiorasp volumio[973]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Apr 20 13:16:27 volumiorasp volumio[973]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) Apr 20 13:16:27 volumiorasp volumio[973]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) Apr 20 13:16:27 volumiorasp volumio[973]: at Socket.emit (events.js:315:20) Apr 20 13:16:27 volumiorasp volumio[973]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 20 13:16:27 volumiorasp volumio[973]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 20 13:16:27 volumiorasp volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:16:27 volumiorasp sudo[8477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 13:15 Apr 20 13:16:27 volumiorasp sudo[8477]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"