-- Logs begin at Mon 2025-03-17 16:23:31 CET, end at Mon 2025-03-17 16:29:03 CET. -- Mar 17 16:28:00 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:00 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:00 volumiorb volumio[1586]: 0 bytes copied, 4.0609e-05 s, 0.0 kB/s Mar 17 16:28:00 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19719, instance 3, run: true Mar 17 16:28:01 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:01 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:01 volumiorb volumio[1586]: 0 bytes copied, 3.8531e-05 s, 0.0 kB/s Mar 17 16:28:01 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19724, instance 3, run: true Mar 17 16:28:02 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:02 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:02 volumiorb volumio[1586]: 0 bytes copied, 3.6872e-05 s, 0.0 kB/s Mar 17 16:28:02 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19729, instance 3, run: true Mar 17 16:28:03 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:03 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:03 volumiorb volumio[1586]: 0 bytes copied, 2.2718e-05 s, 0.0 kB/s Mar 17 16:28:03 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19734, instance 3, run: true Mar 17 16:28:04 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:04 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:04 volumiorb volumio[1586]: 0 bytes copied, 3.9631e-05 s, 0.0 kB/s Mar 17 16:28:04 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19739, instance 3, run: true Mar 17 16:28:05 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:05 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:05 volumiorb volumio[1586]: 0 bytes copied, 2.3105e-05 s, 0.0 kB/s Mar 17 16:28:05 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19744, instance 3, run: true Mar 17 16:28:06 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:06 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:06 volumiorb volumio[1586]: 0 bytes copied, 2.2791e-05 s, 0.0 kB/s Mar 17 16:28:06 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19749, instance 3, run: true Mar 17 16:28:07 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:07 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:07 volumiorb volumio[1586]: 0 bytes copied, 2.2542e-05 s, 0.0 kB/s Mar 17 16:28:07 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19754, instance 3, run: true Mar 17 16:28:08 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:08 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:08 volumiorb volumio[1586]: 0 bytes copied, 4.138e-05 s, 0.0 kB/s Mar 17 16:28:08 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19759, instance 3, run: true Mar 17 16:28:09 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:09 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:09 volumiorb volumio[1586]: 0 bytes copied, 2.8033e-05 s, 0.0 kB/s Mar 17 16:28:09 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19778, instance 3, run: true Mar 17 16:28:10 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:10 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:10 volumiorb volumio[1586]: 0 bytes copied, 3.8157e-05 s, 0.0 kB/s Mar 17 16:28:10 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19783, instance 3, run: true Mar 17 16:28:11 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:11 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:11 volumiorb volumio[1586]: 0 bytes copied, 3.9895e-05 s, 0.0 kB/s Mar 17 16:28:11 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19788, instance 3, run: true Mar 17 16:28:12 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:12 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:12 volumiorb volumio[1586]: 0 bytes copied, 3.7745e-05 s, 0.0 kB/s Mar 17 16:28:12 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19793, instance 3, run: true Mar 17 16:28:13 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:13 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:13 volumiorb volumio[1586]: 0 bytes copied, 3.8028e-05 s, 0.0 kB/s Mar 17 16:28:13 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19798, instance 3, run: true Mar 17 16:28:14 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:14 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:14 volumiorb volumio[1586]: 0 bytes copied, 3.8455e-05 s, 0.0 kB/s Mar 17 16:28:14 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19806, instance 3, run: true Mar 17 16:28:15 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:15 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:15 volumiorb volumio[1586]: 0 bytes copied, 4.0034e-05 s, 0.0 kB/s Mar 17 16:28:15 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19811, instance 3, run: true Mar 17 16:28:16 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:16 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:16 volumiorb volumio[1586]: 0 bytes copied, 3.8013e-05 s, 0.0 kB/s Mar 17 16:28:16 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19816, instance 3, run: true Mar 17 16:28:17 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:17 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:17 volumiorb volumio[1586]: 0 bytes copied, 2.3053e-05 s, 0.0 kB/s Mar 17 16:28:17 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19824, instance 3, run: true Mar 17 16:28:18 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:18 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:18 volumiorb volumio[1586]: 0 bytes copied, 4.0114e-05 s, 0.0 kB/s Mar 17 16:28:18 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19829, instance 3, run: true Mar 17 16:28:19 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:19 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:19 volumiorb volumio[1586]: 0 bytes copied, 3.8247e-05 s, 0.0 kB/s Mar 17 16:28:19 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19849, instance 3, run: true Mar 17 16:28:20 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:20 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:20 volumiorb volumio[1586]: 0 bytes copied, 2.4116e-05 s, 0.0 kB/s Mar 17 16:28:20 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19854, instance 3, run: true Mar 17 16:28:21 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:21 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:21 volumiorb volumio[1586]: 0 bytes copied, 3.8025e-05 s, 0.0 kB/s Mar 17 16:28:21 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19859, instance 3, run: true Mar 17 16:28:21 volumiorb volumio[1586]: info: [squeezelite_mc] 'client' notification received from DESKTOP-4SL9D7G (192.168.2.71); type is 'null' Mar 17 16:28:22 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:22 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:22 volumiorb volumio[1586]: 0 bytes copied, 3.6162e-05 s, 0.0 kB/s Mar 17 16:28:22 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19864, instance 3, run: true Mar 17 16:28:23 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:23 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:23 volumiorb volumio[1586]: 0 bytes copied, 3.8129e-05 s, 0.0 kB/s Mar 17 16:28:23 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19869, instance 3, run: true Mar 17 16:28:24 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:24 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:24 volumiorb volumio[1586]: 0 bytes copied, 3.7688e-05 s, 0.0 kB/s Mar 17 16:28:24 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19874, instance 3, run: true Mar 17 16:28:25 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:25 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:25 volumiorb volumio[1586]: 0 bytes copied, 2.4165e-05 s, 0.0 kB/s Mar 17 16:28:25 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19879, instance 3, run: true Mar 17 16:28:26 volumiorb volumio[1586]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Mar 17 16:28:26 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp - Launching CamillaDsp GUI Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp loaded Mar 17 16:28:26 volumiorb sudo[19883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 17 16:28:26 volumiorb sudo[19883]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:28:26 volumiorb sudo[19883]: pam_unix(sudo:session): session closed for user root Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:26 volumiorb volumio[1586]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:28:26 volumiorb volumio[1586]: info: CoreCommandRouter::Close All Modals sent Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp - IP adress is ---------------------------192.168.2.75 Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp - Mar 17 16:28:26 volumiorb volumio[1586]: info: FusionDsp - undefined Mar 17 16:28:26 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:26 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:26 volumiorb volumio[1586]: 0 bytes copied, 2.2778e-05 s, 0.0 kB/s Mar 17 16:28:26 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19888, instance 3, run: true Mar 17 16:28:27 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:27 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:27 volumiorb volumio[1586]: 0 bytes copied, 2.4558e-05 s, 0.0 kB/s Mar 17 16:28:27 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19893, instance 3, run: true Mar 17 16:28:27 volumiorb volumio[1586]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Mar 17 16:28:27 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Mar 17 16:28:27 volumiorb volumio[1586]: info: FusionDsp - Launching CamillaDsp GUI Mar 17 16:28:27 volumiorb volumio[1586]: info: FusionDsp loaded Mar 17 16:28:27 volumiorb sudo[19897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 17 16:28:27 volumiorb sudo[19897]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:28:27 volumiorb sudo[19897]: pam_unix(sudo:session): session closed for user root Mar 17 16:28:28 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:28 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:28 volumiorb volumio[1586]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:28:28 volumiorb volumio[1586]: info: CoreCommandRouter::Close All Modals sent Mar 17 16:28:28 volumiorb volumio[1586]: info: FusionDsp - IP adress is ---------------------------192.168.2.75 Mar 17 16:28:28 volumiorb volumio[1586]: info: FusionDsp - Mar 17 16:28:28 volumiorb volumio[1586]: info: FusionDsp - undefined Mar 17 16:28:28 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:28 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:28 volumiorb volumio[1586]: 0 bytes copied, 5.1213e-05 s, 0.0 kB/s Mar 17 16:28:28 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19902, instance 3, run: true Mar 17 16:28:29 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:29 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:29 volumiorb volumio[1586]: 0 bytes copied, 2.5728e-05 s, 0.0 kB/s Mar 17 16:28:29 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19921, instance 3, run: true Mar 17 16:28:30 volumiorb volumio[1586]: info: CoreCommandRouter::volumioGetState Mar 17 16:28:30 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:30 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:30 volumiorb volumio[1586]: 0 bytes copied, 3.772e-05 s, 0.0 kB/s Mar 17 16:28:30 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19926, instance 3, run: true Mar 17 16:28:31 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPlay Mar 17 16:28:31 volumiorb volumio[1586]: info: CoreStateMachine::play index undefined Mar 17 16:28:31 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:28:31 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:31 volumiorb volumio[1586]: info: CoreStateMachine::startPlaybackTimer Mar 17 16:28:31 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:31 volumiorb volumio[1586]: info: [1742225311560] ControllerTidal::resume Mar 17 16:28:31 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 17 16:28:31 volumiorb volumio[1586]: info: ControllerMpd::resume Mar 17 16:28:31 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand play Mar 17 16:28:31 volumiorb volumio[1586]: info: sendMpdCommand play took 1 milliseconds Mar 17 16:28:31 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:31 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:31 volumiorb volumio[1586]: 0 bytes copied, 4.0443e-05 s, 0.0 kB/s Mar 17 16:28:31 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19931, instance 3, run: true Mar 17 16:28:32 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:32 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:32 volumiorb volumio[1586]: 0 bytes copied, 3.6133e-05 s, 0.0 kB/s Mar 17 16:28:32 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19980, instance 3, run: true Mar 17 16:28:33 volumiorb volumio[1586]: info: CoreCommandRouter::volumioNext Mar 17 16:28:33 volumiorb volumio[1586]: info: CoreStateMachine::next Mar 17 16:28:33 volumiorb volumio[1586]: info: ControllerMpd::next Mar 17 16:28:33 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand next Mar 17 16:28:33 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:33 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:33 volumiorb volumio[1586]: 0 bytes copied, 2.3288e-05 s, 0.0 kB/s Mar 17 16:28:33 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19985, instance 3, run: true Mar 17 16:28:34 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:34 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:34 volumiorb volumio[1586]: 0 bytes copied, 4.0155e-05 s, 0.0 kB/s Mar 17 16:28:34 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19993, instance 3, run: true Mar 17 16:28:36 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:36 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:36 volumiorb volumio[1586]: 0 bytes copied, 3.9715e-05 s, 0.0 kB/s Mar 17 16:28:36 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 19999, instance 3, run: true Mar 17 16:28:37 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:37 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:37 volumiorb volumio[1586]: 0 bytes copied, 3.6982e-05 s, 0.0 kB/s Mar 17 16:28:37 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20004, instance 3, run: true Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPlay Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::play index 1 Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::stop Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::updateTrackBlock Mar 17 16:28:37 volumiorb volumio[1586]: info: CorePlayQueue::getTrackBlock Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::stPlaybackTimer Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:37 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::serviceStop Mar 17 16:28:37 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreCommandRouter::serviceStop Mar 17 16:28:37 volumiorb volumio[1586]: info: [1742225317907] ControllerTidal::stop Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 17 16:28:37 volumiorb volumio[1586]: info: ControllerMpd::stop Mar 17 16:28:37 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand stop Mar 17 16:28:37 volumiorb volumio[1586]: info: sendMpdCommand stop took 6 milliseconds Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::play index undefined Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:28:37 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::startPlaybackTimer Mar 17 16:28:37 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:37 volumiorb volumio[1586]: info: [1742225317914] ControllerTidal::clearAddPlayTrack Mar 17 16:28:37 volumiorb volumio[1586]: info: Getting stream with soundQuality HI_RES Mar 17 16:28:37 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand stop Mar 17 16:28:37 volumiorb volumio[1586]: info: sendMpdCommand stop took 0 milliseconds Mar 17 16:28:37 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand clear Mar 17 16:28:37 volumiorb volumio[1586]: info: sendMpdCommand clear took 0 milliseconds Mar 17 16:28:37 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES" Mar 17 16:28:37 volumiorb volumio[1586]: info: Mar 17 16:28:37 volumiorb volumio[1586]: ---------------------------- MPD announces system playlist update Mar 17 16:28:37 volumiorb volumio[1586]: info: Ignoring MPD Status Update Mar 17 16:28:37 volumiorb volumio[1586]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES" took 1 milliseconds Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 17 16:28:37 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand play Mar 17 16:28:37 volumiorb volumio[1586]: info: Mar 17 16:28:37 volumiorb volumio[1586]: ---------------------------- MPD announces system playlist update Mar 17 16:28:37 volumiorb volumio[1586]: info: Ignoring MPD Status Update Mar 17 16:28:37 volumiorb volumio[1586]: info: Mar 17 16:28:37 volumiorb volumio[1586]: ---------------------------- MPD announces system playlist update Mar 17 16:28:37 volumiorb volumio[1586]: info: Ignoring MPD Status Update Mar 17 16:28:37 volumiorb volumio[1586]: STREAMING PROXY: Handling url /?data=tidal://song/392741655&soundQuality=HI_RES Mar 17 16:28:37 volumiorb volumio[1586]: info: Mar 17 16:28:37 volumiorb volumio[1586]: ---------------------------- MPD announces system playlist update Mar 17 16:28:37 volumiorb volumio[1586]: info: Ignoring MPD Status Update Mar 17 16:28:37 volumiorb volumio[1586]: info: ------------------------------ 2ms Mar 17 16:28:37 volumiorb volumio[1586]: info: sendMpdCommand play took 2 milliseconds Mar 17 16:28:37 volumiorb volumio[1586]: info: ------------------------------ 2ms Mar 17 16:28:37 volumiorb volumio[1586]: info: ------------------------------ 2ms Mar 17 16:28:37 volumiorb volumio[1586]: info: Mar 17 16:28:37 volumiorb volumio[1586]: ---------------------------- MPD announces system playlist update Mar 17 16:28:37 volumiorb volumio[1586]: info: Ignoring MPD Status Update Mar 17 16:28:37 volumiorb volumio[1586]: info: Mar 17 16:28:37 volumiorb volumio[1586]: ---------------------------- MPD announces system playlist update Mar 17 16:28:37 volumiorb volumio[1586]: info: Ignoring MPD Status Update Mar 17 16:28:37 volumiorb volumio[1586]: info: ------------------------------ 1ms Mar 17 16:28:37 volumiorb volumio[1586]: info: ------------------------------ 0ms Mar 17 16:28:37 volumiorb volumio[1586]: info: ------------------------------ 0ms Mar 17 16:28:37 volumiorb volumio[1586]: info: Executing endpoint getStreamUrltidal Mar 17 16:28:37 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Mar 17 16:28:38 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:38 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:38 volumiorb volumio[1586]: 0 bytes copied, 3.7932e-05 s, 0.0 kB/s Mar 17 16:28:38 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20011, instance 3, run: true Mar 17 16:28:38 volumiorb volumio[1586]: info: getStreamUrl took 269 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MWEyOWMzNDQ4ZGQ4ZDg1MmM3YzliZmM2MjA5MjkwMC5tcDQ/0.flac?Expires=1742228747&Signature=ruzy7xSkKYOsOob3NdBWA9bgBnRbJP77uOKwqZQrqITauAs9Z-EpuCg4exCQ2L~yhaymCi03pe2y~wqhGTptjdI~bGJwMZzlaFFNMrHKL3eqEJ5ehuUaIGOtqBtFaW4UJ3hPnQzVaPtPxSkmmcMvdgW1~MyYLTRiiHv3uCKf0VLGzSi1Ez2LQaty~YnJL3jqbVLwcK5Xg6ptyYbEmj7mpsMdNgrYKi2bJcjFg5F9Hm0RQBLL9076-v7cNSy2lSSnrAF0cN~cuea427ggMS0oZpwf9BIeKbCWgYXS0igRj6lMs9bnDod8Fllj6bYy6j~YrPejEqMaUMyWErwK~7YH8Q__&Key-Pair-Id=K14LZCZ9QUI4JL Mar 17 16:28:38 volumiorb volumio[1586]: STREAMING PROXY: Response: 200, length: 33077674 Mar 17 16:28:38 volumiorb volumio[1586]: info: Mar 17 16:28:38 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:38 volumiorb volumio[1586]: info: Mar 17 16:28:38 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:38 volumiorb volumio[1586]: info: Mar 17 16:28:38 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:28:38 volumiorb volumio[1586]: info: Mar 17 16:28:38 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand status took 9 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand status took 9 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand status took 8 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:38 volumiorb volumio[1586]: info: Mar 17 16:28:38 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:38 volumiorb volumio[1586]: info: Mar 17 16:28:38 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand status took 2 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand status took 1 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand status took 1 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:38 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:38 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService play Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus stop Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:38 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:38 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService play Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus play Mar 17 16:28:38 volumiorb volumio[1586]: info: Received an update from plugin. extracting info from payload Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:38 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:38 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService play Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus play Mar 17 16:28:38 volumiorb volumio[1586]: info: Received an update from plugin. extracting info from payload Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: ------------------------------ 18ms Mar 17 16:28:38 volumiorb volumio[1586]: info: ------------------------------ 27ms Mar 17 16:28:38 volumiorb volumio[1586]: info: ------------------------------ 27ms Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:38 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:38 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:38 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService play Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus play Mar 17 16:28:38 volumiorb volumio[1586]: info: Received an update from plugin. extracting info from payload Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:38 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:38 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService play Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus play Mar 17 16:28:38 volumiorb volumio[1586]: info: Received an update from plugin. extracting info from payload Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:38 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:38 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService play Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus play Mar 17 16:28:38 volumiorb volumio[1586]: info: Received an update from plugin. extracting info from payload Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:38 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:38 volumiorb volumio[1586]: info: ------------------------------ 41ms Mar 17 16:28:38 volumiorb volumio[1586]: info: ------------------------------ 40ms Mar 17 16:28:38 volumiorb volumio[1586]: info: ------------------------------ 40ms Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:38 volumiorb volumio[1586]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 17 16:28:38 volumiorb volumio[1586]: STREAMING PROXY: Client dropped request, destroying Mar 17 16:28:39 volumiorb volumio[1586]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Mar 17 16:28:39 volumiorb volumio[1586]: 1+1 records in Mar 17 16:28:39 volumiorb volumio[1586]: 1+1 records out Mar 17 16:28:39 volumiorb volumio[1586]: 64808 bytes (65 kB, 63 KiB) copied, 0.000117807 s, 550 MB/s Mar 17 16:28:39 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20025, instance 3, run: true Mar 17 16:28:40 volumiorb volumio[1586]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Mar 17 16:28:40 volumiorb volumio[1586]: 1+36 records in Mar 17 16:28:40 volumiorb volumio[1586]: 1+36 records out Mar 17 16:28:40 volumiorb volumio[1586]: 206144 bytes (206 kB, 201 KiB) copied, 0.000122885 s, 1.7 GB/s Mar 17 16:28:40 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20045, instance 3, run: true Mar 17 16:28:41 volumiorb volumio[1586]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Mar 17 16:28:41 volumiorb volumio[1586]: 1+1 records in Mar 17 16:28:41 volumiorb volumio[1586]: 1+1 records out Mar 17 16:28:41 volumiorb volumio[1586]: 57744 bytes (58 kB, 56 KiB) copied, 9.7058e-05 s, 595 MB/s Mar 17 16:28:41 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20050, instance 3, run: true Mar 17 16:28:42 volumiorb volumio[1586]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Mar 17 16:28:42 volumiorb volumio[1586]: 1+1 records in Mar 17 16:28:42 volumiorb volumio[1586]: 1+1 records out Mar 17 16:28:42 volumiorb volumio[1586]: 58848 bytes (59 kB, 57 KiB) copied, 7.5942e-05 s, 775 MB/s Mar 17 16:28:42 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20055, instance 3, run: true Mar 17 16:28:43 volumiorb volumio[1586]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Mar 17 16:28:43 volumiorb volumio[1586]: 1+1 records in Mar 17 16:28:43 volumiorb volumio[1586]: 1+1 records out Mar 17 16:28:43 volumiorb volumio[1586]: 62680 bytes (63 kB, 61 KiB) copied, 7.574e-05 s, 828 MB/s Mar 17 16:28:43 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20060, instance 3, run: true Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::stPlaybackTimer Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::servicePause Mar 17 16:28:44 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::servicePause Mar 17 16:28:44 volumiorb volumio[1586]: info: [1742225324104] ControllerTidal::pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::pause Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand pause Mar 17 16:28:44 volumiorb volumio[1586]: info: Mar 17 16:28:44 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand pause took 0 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:44 volumiorb volumio[1586]: info: Mar 17 16:28:44 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:44 volumiorb volumio[1586]: info: Mar 17 16:28:44 volumiorb volumio[1586]: ---------------------------- MPD announces state update: player Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::getState Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand status Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand status took 0 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand status took 0 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand status took 0 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::parseState Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:44 volumiorb volumio[1586]: verbose: ControllerMpd::parseTrackInfo Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:44 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105476,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1695 Kbps","isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:44 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::stPlaybackTimer Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:44 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105476,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1695 Kbps","isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:44 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::stPlaybackTimer Mar 17 16:28:44 volumiorb volumio[1586]: info: ControllerMpd::pushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::servicePushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CorePlayQueue::getTrack 1 Mar 17 16:28:44 volumiorb volumio[1586]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":105476,"duration":157,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1695 Kbps","isStreaming":false,"title":"392741655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/392741655&soundQuality=HI_RES","trackType":"tidal"} Mar 17 16:28:44 volumiorb volumio[1586]: verbose: CURRENT POSITION 1 Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::syncState stateService pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::syncState currentStatus pause Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::pushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreCommandRouter::volumioPushState Mar 17 16:28:44 volumiorb volumio[1586]: info: CoreStateMachine::stPlaybackTimer Mar 17 16:28:44 volumiorb volumio[1586]: info: ------------------------------ 10ms Mar 17 16:28:44 volumiorb volumio[1586]: info: ------------------------------ 9ms Mar 17 16:28:44 volumiorb volumio[1586]: info: ------------------------------ 9ms Mar 17 16:28:44 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:44 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:44 volumiorb volumio[1586]: 0 bytes copied, 2.0649e-05 s, 0.0 kB/s Mar 17 16:28:44 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20065, instance 3, run: true Mar 17 16:28:44 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20068, instance 2, run: true Mar 17 16:28:45 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:45 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:45 volumiorb volumio[1586]: 0 bytes copied, 1.904e-05 s, 0.0 kB/s Mar 17 16:28:45 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20074, instance 3, run: true Mar 17 16:28:45 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:45 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:45 volumiorb volumio[1586]: 0 bytes copied, 1.9376e-05 s, 0.0 kB/s Mar 17 16:28:45 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20079, instance 2, run: true Mar 17 16:28:46 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:46 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:46 volumiorb volumio[1586]: 0 bytes copied, 1.9327e-05 s, 0.0 kB/s Mar 17 16:28:46 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20084, instance 3, run: true Mar 17 16:28:46 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:46 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:46 volumiorb volumio[1586]: 0 bytes copied, 1.9899e-05 s, 0.0 kB/s Mar 17 16:28:46 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20089, instance 2, run: true Mar 17 16:28:47 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:47 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:47 volumiorb volumio[1586]: 0 bytes copied, 2.0144e-05 s, 0.0 kB/s Mar 17 16:28:47 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20094, instance 3, run: true Mar 17 16:28:47 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:47 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:47 volumiorb volumio[1586]: 0 bytes copied, 1.9279e-05 s, 0.0 kB/s Mar 17 16:28:47 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20099, instance 2, run: true Mar 17 16:28:48 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:48 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:48 volumiorb volumio[1586]: 0 bytes copied, 1.9474e-05 s, 0.0 kB/s Mar 17 16:28:48 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20104, instance 3, run: true Mar 17 16:28:48 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:48 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:48 volumiorb volumio[1586]: 0 bytes copied, 1.9607e-05 s, 0.0 kB/s Mar 17 16:28:48 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20109, instance 2, run: true Mar 17 16:28:49 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:49 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:49 volumiorb volumio[1586]: 0 bytes copied, 2.1543e-05 s, 0.0 kB/s Mar 17 16:28:49 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20114, instance 3, run: true Mar 17 16:28:49 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:49 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:49 volumiorb volumio[1586]: 0 bytes copied, 2.0958e-05 s, 0.0 kB/s Mar 17 16:28:49 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20119, instance 2, run: true Mar 17 16:28:50 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:50 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:50 volumiorb volumio[1586]: 0 bytes copied, 2.0965e-05 s, 0.0 kB/s Mar 17 16:28:50 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20138, instance 3, run: true Mar 17 16:28:50 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:50 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:50 volumiorb volumio[1586]: 0 bytes copied, 1.9993e-05 s, 0.0 kB/s Mar 17 16:28:50 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20143, instance 2, run: true Mar 17 16:28:51 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:51 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:51 volumiorb volumio[1586]: 0 bytes copied, 2.0473e-05 s, 0.0 kB/s Mar 17 16:28:51 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20148, instance 3, run: true Mar 17 16:28:51 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:51 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:51 volumiorb volumio[1586]: 0 bytes copied, 2.0482e-05 s, 0.0 kB/s Mar 17 16:28:51 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20153, instance 2, run: true Mar 17 16:28:52 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:52 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:52 volumiorb volumio[1586]: 0 bytes copied, 2.0595e-05 s, 0.0 kB/s Mar 17 16:28:52 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20158, instance 3, run: true Mar 17 16:28:52 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:52 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:52 volumiorb volumio[1586]: 0 bytes copied, 2.0352e-05 s, 0.0 kB/s Mar 17 16:28:52 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20163, instance 2, run: true Mar 17 16:28:53 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:53 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:53 volumiorb volumio[1586]: 0 bytes copied, 2.1155e-05 s, 0.0 kB/s Mar 17 16:28:53 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20168, instance 3, run: true Mar 17 16:28:53 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:53 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:53 volumiorb volumio[1586]: 0 bytes copied, 1.9388e-05 s, 0.0 kB/s Mar 17 16:28:53 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20173, instance 2, run: true Mar 17 16:28:54 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:54 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:54 volumiorb volumio[1586]: 0 bytes copied, 1.865e-05 s, 0.0 kB/s Mar 17 16:28:54 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20181, instance 3, run: true Mar 17 16:28:54 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:54 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:54 volumiorb volumio[1586]: 0 bytes copied, 2.0076e-05 s, 0.0 kB/s Mar 17 16:28:54 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20186, instance 2, run: true Mar 17 16:28:55 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:55 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:55 volumiorb volumio[1586]: 0 bytes copied, 1.9472e-05 s, 0.0 kB/s Mar 17 16:28:55 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20191, instance 3, run: true Mar 17 16:28:55 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:55 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:55 volumiorb volumio[1586]: 0 bytes copied, 1.999e-05 s, 0.0 kB/s Mar 17 16:28:55 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20196, instance 2, run: true Mar 17 16:28:56 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:56 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:56 volumiorb volumio[1586]: 0 bytes copied, 2.0326e-05 s, 0.0 kB/s Mar 17 16:28:56 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20201, instance 3, run: true Mar 17 16:28:56 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:56 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:56 volumiorb volumio[1586]: 0 bytes copied, 1.8371e-05 s, 0.0 kB/s Mar 17 16:28:56 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20206, instance 2, run: true Mar 17 16:28:57 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:57 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:57 volumiorb volumio[1586]: 0 bytes copied, 1.8809e-05 s, 0.0 kB/s Mar 17 16:28:57 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20211, instance 3, run: true Mar 17 16:28:57 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:57 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:57 volumiorb volumio[1586]: 0 bytes copied, 1.9808e-05 s, 0.0 kB/s Mar 17 16:28:57 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20216, instance 2, run: true Mar 17 16:28:58 volumiorb volumio[1586]: info: CoreCommandRouter::volumioGetState Mar 17 16:28:58 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:58 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:58 volumiorb volumio[1586]: 0 bytes copied, 1.9617e-05 s, 0.0 kB/s Mar 17 16:28:58 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20221, instance 3, run: true Mar 17 16:28:58 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:58 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:58 volumiorb volumio[1586]: 0 bytes copied, 1.9906e-05 s, 0.0 kB/s Mar 17 16:28:58 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20226, instance 2, run: true Mar 17 16:28:59 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:59 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:59 volumiorb volumio[1586]: 0 bytes copied, 1.8367e-05 s, 0.0 kB/s Mar 17 16:28:59 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20246, instance 3, run: true Mar 17 16:28:59 volumiorb volumio[1586]: 0+0 records in Mar 17 16:28:59 volumiorb volumio[1586]: 0+0 records out Mar 17 16:28:59 volumiorb volumio[1586]: 0 bytes copied, 2.0875e-05 s, 0.0 kB/s Mar 17 16:28:59 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20251, instance 2, run: true Mar 17 16:28:59 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 17 16:28:59 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 17 16:29:00 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:00 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:00 volumiorb volumio[1586]: 0 bytes copied, 1.8824e-05 s, 0.0 kB/s Mar 17 16:29:00 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20256, instance 3, run: true Mar 17 16:29:00 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:00 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:00 volumiorb volumio[1586]: 0 bytes copied, 1.9324e-05 s, 0.0 kB/s Mar 17 16:29:00 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20261, instance 2, run: true Mar 17 16:29:01 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:01 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:01 volumiorb volumio[1586]: 0 bytes copied, 1.9886e-05 s, 0.0 kB/s Mar 17 16:29:01 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20266, instance 3, run: true Mar 17 16:29:01 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:01 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:01 volumiorb volumio[1586]: 0 bytes copied, 2.0338e-05 s, 0.0 kB/s Mar 17 16:29:01 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20271, instance 2, run: true Mar 17 16:29:02 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:02 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:02 volumiorb volumio[1586]: 0 bytes copied, 1.825e-05 s, 0.0 kB/s Mar 17 16:29:02 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20276, instance 3, run: true Mar 17 16:29:02 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:02 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:02 volumiorb volumio[1586]: 0 bytes copied, 2.1083e-05 s, 0.0 kB/s Mar 17 16:29:02 volumiorb volumio[1586]: info: camilladsp spawned new process with pid 20281, instance 2, run: true Mar 17 16:29:03 volumiorb volumio[1586]: info: Starting Uninstall of plugin audio_interface - fusiondsp Mar 17 16:29:03 volumiorb volumio[1586]: info: Uninstalling plugin fusiondsp Mar 17 16:29:03 volumiorb volumio[1586]: info: FusionDsp - Stopping FusionDsp service Mar 17 16:29:03 volumiorb volumio[1586]: info: camilladsp service terminated, instance 3 Mar 17 16:29:03 volumiorb volumio[1586]: info: Disabling plugin fusiondsp Mar 17 16:29:03 volumiorb volumio[1586]: info: Preparing to generate the ALSA configuration file Mar 17 16:29:03 volumiorb sudo[20285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Mar 17 16:29:03 volumiorb sudo[20285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb systemd[1]: Stopping FusionDsp Daemon... Mar 17 16:29:03 volumiorb volumio[4847]: Backend configuration: Mar 17 16:29:03 volumiorb volumio[4847]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 17 16:29:03 volumiorb volumio[4847]: active_config_txt: null Mar 17 16:29:03 volumiorb volumio[4847]: camilla_host: 127.0.0.1 Mar 17 16:29:03 volumiorb volumio[4847]: camilla_port: 9876 Mar 17 16:29:03 volumiorb volumio[4847]: coeff_dir: /data/INTERNAL/FusionDsp/filters Mar 17 16:29:03 volumiorb volumio[4847]: config_dir: /data/configuration/audio_interface/fusiondsp Mar 17 16:29:03 volumiorb volumio[4847]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 17 16:29:03 volumiorb volumio[4847]: log_file: /tmp/camilladsp.log Mar 17 16:29:03 volumiorb volumio[4847]: on_get_active_config: null Mar 17 16:29:03 volumiorb volumio[4847]: on_set_active_config: null Mar 17 16:29:03 volumiorb volumio[4847]: port: 5011 Mar 17 16:29:03 volumiorb volumio[4847]: supported_capture_types: null Mar 17 16:29:03 volumiorb volumio[4847]: supported_playback_types: null Mar 17 16:29:03 volumiorb volumio[4847]: update_config_symlink: false Mar 17 16:29:03 volumiorb volumio[4847]: update_config_txt: false Mar 17 16:29:03 volumiorb volumio[4847]: ======== Running on http://0.0.0.0:5011 ======== Mar 17 16:29:03 volumiorb volumio[4847]: (Press CTRL+C to quit) Mar 17 16:29:03 volumiorb volumio[1586]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 17 16:29:03 volumiorb volumio[1586]: info: Asound.conf file written Mar 17 16:29:03 volumiorb sudo[20288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 17 16:29:03 volumiorb sudo[20288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb sudo[20288]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb systemd[1]: fusiondsp.service: Succeeded. Mar 17 16:29:03 volumiorb systemd[1]: Stopped FusionDsp Daemon. Mar 17 16:29:03 volumiorb volumio[1586]: No protocol specified Mar 17 16:29:03 volumiorb volumio[1586]: xcb_connection_has_error() returned true Mar 17 16:29:03 volumiorb volumio[1586]: info: Output device has changed, restarting MPD Mar 17 16:29:03 volumiorb sudo[20285]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb sudo[20295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:29:03 volumiorb sudo[20295]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb sudo[20295]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb volumio[1586]: info: Output device has changed, restarting Shairport Sync Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:29:03 volumiorb sudo[20298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:29:03 volumiorb sudo[20298]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb systemd[1]: Stopping Music Player Daemon... Mar 17 16:29:03 volumiorb systemd[1]: mpd.service: Succeeded. Mar 17 16:29:03 volumiorb systemd[1]: Stopped Music Player Daemon. Mar 17 16:29:03 volumiorb volumio[1586]: No protocol specified Mar 17 16:29:03 volumiorb volumio[1586]: xcb_connection_has_error() returned true Mar 17 16:29:03 volumiorb volumio[1586]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:29:03 volumiorb volumio[1586]: No protocol specified Mar 17 16:29:03 volumiorb volumio[1586]: xcb_connection_has_error() returned true Mar 17 16:29:03 volumiorb volumio[1586]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb vtcs[5548]: [2025-03-17 16:29:03.685] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 17 16:29:03 volumiorb vtcs[5548]: [2025-03-17 16:29:03.686] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 17 16:29:03 volumiorb vtcs[5548]: [2025-03-17 16:29:03.686] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe Mar 17 16:29:03 volumiorb systemd[1]: Starting Music Player Daemon... Mar 17 16:29:03 volumiorb volumio[1586]: info: Checking if uninstall.sh is present Mar 17 16:29:03 volumiorb sudo[20308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 17 16:29:03 volumiorb sudo[20308]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb systemd[1]: Stopping Volumio Tidal Connect Service... Mar 17 16:29:03 volumiorb systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 17 16:29:03 volumiorb systemd[1]: vtcs.service: Succeeded. Mar 17 16:29:03 volumiorb systemd[1]: Stopped Volumio Tidal Connect Service. Mar 17 16:29:03 volumiorb sudo[20308]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb volumio[1586]: info: Executing uninstall.sh Mar 17 16:29:03 volumiorb sudo[20312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 17 16:29:03 volumiorb sudo[20312]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb sudo[20303]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:29:03 volumiorb sudo[20303]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb sudo[20303]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb sudo[20312]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb volumio[1586]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 17 16:29:03 volumiorb volumio[1586]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 17 16:29:03 volumiorb volumio[1586]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 17 16:29:03 volumiorb volumio[1586]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 17 16:29:03 volumiorb volumio[1586]: info: MPD Permissions set Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::volumioGetState Mar 17 16:29:03 volumiorb volumio[1586]: info: FusionDsp - Reporting Fusion DSP Disabled Mar 17 16:29:03 volumiorb sudo[20318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Mar 17 16:29:03 volumiorb volumio[1586]: info: Removing fusiondspeq DSP Signal Path Element Mar 17 16:29:03 volumiorb sudo[20318]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 17 16:29:03 volumiorb sudo[20327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Mar 17 16:29:03 volumiorb sudo[20327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:29:03 volumiorb sudo[20327]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb sudo[20318]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:29:03 volumiorb volumio[1586]: info: Uninstall script completed Mar 17 16:29:03 volumiorb volumio[1586]: info: Removing plugin fusiondsp from configuration Mar 17 16:29:03 volumiorb volumio[1586]: info: Successfully removed fusiondsp configuration files Mar 17 16:29:03 volumiorb volumio[1586]: info: Plugin folders cleanup Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning into folder /volumio/app/plugins/ Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category audio_interface Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category miscellanea Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category music_service Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category plugins.json Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category system_controller Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category user_interface Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning into folder /data/plugins/ Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category audio_interface Mar 17 16:29:03 volumiorb volumio[1586]: info: Cleaning folder for fusiondsp Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category music_service Mar 17 16:29:03 volumiorb volumio[1586]: info: Scanning category user_interface Mar 17 16:29:03 volumiorb volumio[1586]: info: Plugin folders cleanup completed Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 17 16:29:03 volumiorb volumio[1586]: info: [{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"1.0.1","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"1.0.3","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Squeezelite MC","name":"squeezelite_mc","category":"music_service","version":"1.0.4","icon":"fa-play-circle-o","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.3.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.2","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.2.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Mar 17 16:29:03 volumiorb volumio[1586]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 17 16:29:03 volumiorb volumio[1586]: 0+0 records in Mar 17 16:29:03 volumiorb volumio[1586]: 0+0 records out Mar 17 16:29:03 volumiorb volumio[1586]: 0 bytes copied, 2.2648e-05 s, 0.0 kB/s Mar 17 16:29:03 volumiorb volumio[1586]: info: camilladsp spawned new process with pid undefined, instance 2, run: true Mar 17 16:29:03 volumiorb volumio[1586]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 16:29:03 volumiorb volumio[1586]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Mar 17 16:29:03 volumiorb volumio[1586]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Mar 17 16:29:03 volumiorb volumio[1586]: at onErrorNT (internal/child_process.js:472:16) Mar 17 16:29:03 volumiorb volumio[1586]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Mar 17 16:29:03 volumiorb volumio[1586]: errno: -2, Mar 17 16:29:03 volumiorb volumio[1586]: code: 'ENOENT', Mar 17 16:29:03 volumiorb volumio[1586]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Mar 17 16:29:03 volumiorb volumio[1586]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Mar 17 16:29:03 volumiorb volumio[1586]: spawnargs: [ Mar 17 16:29:03 volumiorb volumio[1586]: '-p', Mar 17 16:29:03 volumiorb volumio[1586]: 9876, Mar 17 16:29:03 volumiorb volumio[1586]: '-o', Mar 17 16:29:03 volumiorb volumio[1586]: '/tmp/camilladsp.log', Mar 17 16:29:03 volumiorb volumio[1586]: '-l', Mar 17 16:29:03 volumiorb volumio[1586]: 'warn', Mar 17 16:29:03 volumiorb volumio[1586]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Mar 17 16:29:03 volumiorb volumio[1586]: ] Mar 17 16:29:03 volumiorb volumio[1586]: } Mar 17 16:29:03 volumiorb volumio[1586]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 16:29:03 volumiorb mpd[20316]: Mar 17 16:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 16:29:03 volumiorb systemd[1]: Started Music Player Daemon. Mar 17 16:29:03 volumiorb sudo[20298]: pam_unix(sudo:session): session closed for user root Mar 17 16:29:03 volumiorb sudo[20349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 16:28 Mar 17 16:29:03 volumiorb sudo[20349]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"