-- Logs begin at Sat 2025-06-21 17:39:16 JST, end at Sat 2025-06-21 18:50:56 JST. -- Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::ClearQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::updateTrackBlock Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrackBlock Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::stPlaybackTimer Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::serviceStop Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::serviceStop Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::stop Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::clearPlayQueue Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::saveQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushQueue Jun 21 18:49:04 rivo volumio[3117]: info: Play playlist Keith Jarrett Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand stop took 48 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 9 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 6 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Max-O-Man - 2020 Remastered","artist":"Fourplay","album":"The Best Of Fourplay","uri":"USB/A785-52C8/Hi-Res AUDIO/Fourplay/The Best Of Fourplay/The Best Of Fourplay_01_Max-O-Man - 2020 Remastered.flac","trackType":"flac"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: No code Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 74ms Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 60 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Max-O-Man - 2020 Remastered","artist":"Fourplay","album":"The Best Of Fourplay","uri":"USB/A785-52C8/Hi-Res AUDIO/Fourplay/The Best Of Fourplay/The Best Of Fourplay_01_Max-O-Man - 2020 Remastered.flac","trackType":"flac"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: No code Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 126ms Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioClearQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::ClearQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::clearPlayQueue Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::saveQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioAddQueueItems Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::addQueueItems Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::addQueueItems Jun 21 18:49:04 rivo volumio[3117]: info: Preload queue cleared Jun 21 18:49:04 rivo volumio[3117]: info: Adding Item to queue: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Using cached record of: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Adding Item to queue: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_02_ケルン、1975年1月24日 パートIIA .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Using cached record of: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_02_ケルン、1975年1月24日 パートIIA .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Adding Item to queue: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_03_ケルン、1975年1月24日 パートIIB .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Using cached record of: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_03_ケルン、1975年1月24日 パートIIB .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Adding Item to queue: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_04_ケルン、1975年1月24日 パートIIC .dsf Jun 21 18:49:04 rivo volumio[3117]: info: Using cached record of: mnt/USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_04_ケルン、1975年1月24日 パートIIC .dsf Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushQueue Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::saveQueue Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::updateTrackBlock Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrackBlock Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPlay Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::play index 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::play index undefined Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::startPlaybackTimer Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::clearAddPlayTracks USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 171 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand stop took 8 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Max-O-Man - 2020 Remastered","artist":"Fourplay","album":"The Best Of Fourplay","uri":"USB/A785-52C8/Hi-Res AUDIO/Fourplay/The Best Of Fourplay/The Best Of Fourplay_01_Max-O-Man - 2020 Remastered.flac","trackType":"flac"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus stop Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: No code Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 307ms Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces system playlist update Jun 21 18:49:04 rivo volumio[3117]: info: Ignoring MPD Status Update Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand clear took 103 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand add "USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf" Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces system playlist update Jun 21 18:49:04 rivo volumio[3117]: info: Ignoring MPD Status Update Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces system playlist update Jun 21 18:49:04 rivo volumio[3117]: info: Ignoring MPD Status Update Jun 21 18:49:04 rivo volumio[3117]: error: updateQueue error: null Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces system playlist update Jun 21 18:49:04 rivo volumio[3117]: info: Ignoring MPD Status Update Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 46ms Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand add "USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf" took 44 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 15ms Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 13ms Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand play Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces system playlist update Jun 21 18:49:04 rivo volumio[3117]: info: Ignoring MPD Status Update Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces system playlist update Jun 21 18:49:04 rivo volumio[3117]: info: Ignoring MPD Status Update Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 25ms Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand play took 21 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 19ms Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 17ms Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 45 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 46 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 45 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: Jun 21 18:49:04 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:49:04 rivo volumio[3117]: info: FusionDsp - ---- read samplerate, raw: 176400,S32_LE,2,32 Jun 21 18:49:04 rivo volumio[3117]: info: FusionDsp - ---- read samplerate from file: 176400 Jun 21 18:49:04 rivo volumio[3117]: info: camilladsp stopping service pid 28517... Jun 21 18:49:04 rivo kernel: spdif_a keep clk continuous Jun 21 18:49:04 rivo kernel: aml_spdif_close Jun 21 18:49:04 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 21 18:49:04 rivo volumio[3117]: info: camilladsp service terminated, instance 1 Jun 21 18:49:04 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:49:04 rivo volumio[3117]: info: camilladsp service started and running in background, instance 1 Jun 21 18:49:04 rivo volumio[3117]: info: FusionDsp - ---- read samplerate, raw: 176400,S32_LE,2,32 Jun 21 18:49:04 rivo volumio[3117]: info: FusionDsp - ---- read samplerate from file: 176400 Jun 21 18:49:04 rivo volumio[3117]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 136 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 132 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 131 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 130 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 125 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand status took 123 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus stop Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 21 18:49:04 rivo volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 21 18:49:04 rivo volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 249ms Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 269ms Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 268ms Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 91 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 89 milliseconds Jun 21 18:49:04 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 89 milliseconds Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 21 18:49:04 rivo volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 21 18:49:04 rivo volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:49:04 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 21 18:49:04 rivo volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:49:04 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:49:04 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:49:04 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 350ms Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 334ms Jun 21 18:49:04 rivo volumio[3117]: info: ------------------------------ 331ms Jun 21 18:49:05 rivo volumio[3117]: info: Signalling Playback active due to playback status change Jun 21 18:49:05 rivo volumio[3117]: info: camilladsp spawned new process with pid 28546, instance 1, run: true Jun 21 18:49:05 rivo kernel: aml_spdif_open Jun 21 18:49:05 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 21 18:49:05 rivo kernel: set normal 512 fs /4 fs Jun 21 18:49:05 rivo kernel: set spdifout clk:22579200, mpll:90316800 Jun 21 18:49:05 rivo kernel: get spdifout clk:22579187, mpll:90316746 Jun 21 18:49:05 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jun 21 18:49:05 rivo kernel: set normal 512 fs /4 fs Jun 21 18:49:05 rivo kernel: set spdifout clk:22579200, mpll:90316800 Jun 21 18:49:05 rivo kernel: get spdifout clk:22579187, mpll:90316746 Jun 21 18:49:05 rivo kernel: spdif_info: rate: 176400, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xc00, ch1_r:0xc00 Jun 21 18:49:05 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jun 21 18:49:05 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jun 21 18:49:05 rivo volumio[3117]: info: MCU Signalled Playback Active Jun 21 18:49:06 rivo volumio[3117]: info: Executing endpoint metavolumio Jun 21 18:49:06 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 18:49:06 rivo volumio[3117]: info: Executing endpoint metavolumio Jun 21 18:49:06 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 18:49:06 rivo volumio[3117]: info: Executing endpoint metavolumio Jun 21 18:49:06 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 18:49:22 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:49:22 rivo volumio[3117]: info: FusionDsp - Jun 21 18:49:22 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:49:37 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Jun 21 18:49:37 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jun 21 18:49:37 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:49:37 rivo volumio[3117]: info: FusionDsp - Effects disabled Jun 21 18:49:37 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:49:37 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:49:37 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:49:37 rivo volumio[3117]: info: FusionDsp - Jun 21 18:49:37 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:49:52 rivo kernel: CIFS VFS: Free previous auth_key.response = ffffffc049f49d80 Jun 21 18:49:54 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp enableeffect Jun 21 18:49:54 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Jun 21 18:49:54 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:49:54 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:49:54 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:49:54 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:49:54 rivo volumio[3117]: info: FusionDsp - Jun 21 18:49:54 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:49:55 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 21 18:49:55 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 21 18:49:56 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:49:56 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:49:56 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:49:56 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:49:56 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:49:57 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:01 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Jun 21 18:50:01 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jun 21 18:50:01 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:50:01 rivo volumio[3117]: info: FusionDsp - Effects disabled Jun 21 18:50:01 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:50:01 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:01 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:50:01 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:01 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:04 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp enableeffect Jun 21 18:50:04 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Jun 21 18:50:04 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:50:04 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:50:04 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:04 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:50:04 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:04 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:09 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 21 18:50:09 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 21 18:50:09 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:50:09 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:50:10 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:10 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:50:10 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:10 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:23 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Jun 21 18:50:23 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jun 21 18:50:24 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:50:24 rivo volumio[3117]: info: FusionDsp - Effects disabled Jun 21 18:50:24 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:24 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:50:24 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:50:24 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:24 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:25 rivo volumio[3117]: info: CALLMETHOD: audio_interface fusiondsp enableeffect Jun 21 18:50:25 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Jun 21 18:50:25 rivo volumio[3117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 21 18:50:25 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:25 rivo volumio[3117]: info: CoreCommandRouter::Close All Modals sent Jun 21 18:50:25 rivo volumio[3117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 21 18:50:25 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:25 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioPause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::stPlaybackTimer Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::servicePause Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::servicePause Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::pause Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand pause Jun 21 18:50:41 rivo volumio[3117]: info: Jun 21 18:50:41 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand pause took 8 milliseconds Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:50:41 rivo volumio[3117]: info: Jun 21 18:50:41 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:50:41 rivo volumio[3117]: info: Jun 21 18:50:41 rivo volumio[3117]: ---------------------------- MPD announces state update: player Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::getState Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand status took 10 milliseconds Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand status took 7 milliseconds Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand status took 6 milliseconds Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::parseState Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 21 18:50:41 rivo volumio[3117]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:50:41 rivo volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97416,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:50:41 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::syncState stateService pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:50:41 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::stPlaybackTimer Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97416,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:50:41 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::syncState stateService pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:50:41 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::stPlaybackTimer Jun 21 18:50:41 rivo volumio[3117]: info: ControllerMpd::pushState Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::servicePushState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97416,"duration":1569,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"ケルン、1975年1月24日（パートI）","artist":"キース・ジャレット","album":"ザ・ケルン・コンサート","uri":"USB/A785-52C8/Hi-Res AUDIO/キース・ジャレット/ザ・ケルン・コンサート/ザ・ケルン・コンサート_01_ケルン、1975年1月24日 パートI .dsf","trackType":"dsf"} Jun 21 18:50:41 rivo volumio[3117]: verbose: CURRENT POSITION 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::syncState stateService pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::syncState currentStatus pause Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::pushState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 21 18:50:41 rivo volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 21 18:50:41 rivo volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 21 18:50:41 rivo volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 21 18:50:41 rivo volumio[3117]: info: MRS: Pushing multiroomSync output Jun 21 18:50:41 rivo volumio[3117]: info: CoreStateMachine::stPlaybackTimer Jun 21 18:50:41 rivo volumio[3117]: info: ------------------------------ 111ms Jun 21 18:50:41 rivo volumio[3117]: info: ------------------------------ 107ms Jun 21 18:50:41 rivo volumio[3117]: info: ------------------------------ 105ms Jun 21 18:50:41 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jun 21 18:50:41 rivo kernel: spdif_a keep clk continuous Jun 21 18:50:41 rivo kernel: aml_spdif_close Jun 21 18:50:41 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 21 18:50:41 rivo volumio[3117]: info: camilladsp spawned new process with pid 28853, instance 1, run: true Jun 21 18:50:41 rivo volumio[3117]: info: MCU Signalled Playback Inactive Jun 21 18:50:41 rivo kernel: aml_spdif_open Jun 21 18:50:41 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 21 18:50:41 rivo kernel: set normal 512 fs /4 fs Jun 21 18:50:41 rivo kernel: set spdifout clk:22579200, mpll:90316800 Jun 21 18:50:41 rivo kernel: get spdifout clk:22579187, mpll:90316746 Jun 21 18:50:41 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jun 21 18:50:41 rivo kernel: set normal 512 fs /4 fs Jun 21 18:50:41 rivo kernel: set spdifout clk:22579200, mpll:90316800 Jun 21 18:50:41 rivo kernel: get spdifout clk:22579187, mpll:90316746 Jun 21 18:50:41 rivo kernel: spdif_info: rate: 176400, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xc00, ch1_r:0xc00 Jun 21 18:50:41 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jun 21 18:50:44 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 18:50:44 rivo volumio[3117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 21 18:50:45 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:45 rivo volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 18:50:45 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:45 rivo volumio[3117]: info: FusionDsp - no preset used Jun 21 18:50:45 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:45 rivo volumio[3117]: info: FusionDsp - undefined Jun 21 18:50:55 rivo volumio[3117]: info: Starting Uninstall of plugin audio_interface - fusiondsp Jun 21 18:50:55 rivo volumio[3117]: info: Uninstalling plugin fusiondsp Jun 21 18:50:55 rivo volumio[3117]: info: FusionDsp - Stopping FusionDsp service Jun 21 18:50:55 rivo volumio[3117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 18:50:55 rivo volumio[3117]: TypeError: Cannot read property 'stop' of null Jun 21 18:50:55 rivo volumio[3117]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jun 21 18:50:55 rivo volumio[3117]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 21 18:50:55 rivo volumio[3117]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jun 21 18:50:55 rivo volumio[3117]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jun 21 18:50:55 rivo volumio[3117]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Jun 21 18:50:55 rivo volumio[3117]: at Socket.emit (events.js:400:28) Jun 21 18:50:55 rivo volumio[3117]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 21 18:50:55 rivo volumio[3117]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 21 18:50:55 rivo volumio[3117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 18:50:56 rivo sudo[28907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 18:49 Jun 21 18:50:56 rivo sudo[28907]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 06:01:16 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="5a6f819282ea0c6f84bf87456dce95cb"