-- Logs begin at Tue 2024-09-17 18:06:54 EEST, end at Sun 2024-10-20 21:03:26 EEST. -- Oct 20 21:02:13 volumipi volumio[913]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::addQueueItems Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::addQueueItems Oct 20 21:02:13 volumipi volumio[913]: info: Preload queue cleared Oct 20 21:02:13 volumipi volumio[913]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Oct 20 21:02:13 volumipi volumio[913]: info: Exploding uri INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav in service mpd Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioClearQueue Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:13 volumipi volumio[913]: info: sendMpdCommand playlistinfo took 69860 milliseconds Oct 20 21:02:13 volumipi volumio[913]: verbose: ControllerMpd::parseTrackInfo Oct 20 21:02:13 volumipi volumio[913]: info: ControllerMpd::pushState Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::servicePushState Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:13 volumipi volumio[913]: 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":"512kMeasSweep_30_to_20k_44k_PCM16_LR_refL","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav","trackType":"wav"} Oct 20 21:02:13 volumipi volumio[913]: verbose: CURRENT POSITION 0 Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::syncState stateService stop Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::syncState currentStatus stop Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:13 volumipi volumio[913]: info: No code Oct 20 21:02:13 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:13 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:13 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:13 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:13 volumipi volumio[913]: info: ------------------------------ 73009ms Oct 20 21:02:23 volumipi volumio[913]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::addQueueItems Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::addQueueItems Oct 20 21:02:23 volumipi volumio[913]: info: Preload queue cleared Oct 20 21:02:23 volumipi volumio[913]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Oct 20 21:02:23 volumipi volumio[913]: info: Exploding uri INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav in service mpd Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioClearQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:23 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:23 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:23 volumipi volumio[913]: info: ALBUMART /albumart?cacheid=816&web=FusionDsp/Test%20Signal/extralarge&path=%2Fmnt%2FINTERNAL%2FFusionDsp%2Ftools%2F512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav&metadata=false Oct 20 21:02:23 volumipi volumio[913]: info: URI /mnt/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::updateTrackBlock Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::getTrackBlock Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPlay Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::play index 0 Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::play index undefined Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::startPlaybackTimer Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 20 21:02:23 volumipi volumio[913]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Oct 20 21:02:23 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand stop Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::updateTrackBlock Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::getTrackBlock Oct 20 21:02:23 volumipi volumio[913]: info: CoreCommandRouter::volumioPlay Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::play index 1 Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::play index undefined Oct 20 21:02:23 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:23 volumipi volumio[913]: info: CorePlayQueue::getTrack 1 Oct 20 21:02:34 volumipi volumio[913]: info: CALLMETHOD: audio_interface fusiondsp removetools Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , removetools Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand update Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 20 21:02:34 volumipi volumio[913]: info: Oct 20 21:02:34 volumipi volumio[913]: ---------------------------- MPD announces state update: update Oct 20 21:02:34 volumipi volumio[913]: info: sendMpdCommand stop took 10979 milliseconds Oct 20 21:02:34 volumipi volumio[913]: info: ControllerMpd::getState Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand clear Oct 20 21:02:34 volumipi volumio[913]: info: Oct 20 21:02:34 volumipi volumio[913]: ---------------------------- MPD announces system playlist update Oct 20 21:02:34 volumipi volumio[913]: info: Ignoring MPD Status Update Oct 20 21:02:34 volumipi volumio[913]: info: sendMpdCommand update took 13 milliseconds Oct 20 21:02:34 volumipi volumio[913]: info: sendMpdCommand status took 9 milliseconds Oct 20 21:02:34 volumipi volumio[913]: info: sendMpdCommand status took 6 milliseconds Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::parseState Oct 20 21:02:34 volumipi volumio[913]: info: Command Router : Notfying DB Updatetrue Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::Close All Modals sent Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::parseState Oct 20 21:02:34 volumipi volumio[913]: info: ControllerMpd::pushState Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::servicePushState Oct 20 21:02:34 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:34 volumipi volumio[913]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 20 21:02:34 volumipi volumio[913]: verbose: CURRENT POSITION 0 Oct 20 21:02:34 volumipi volumio[913]: info: CoreStateMachine::syncState stateService null Oct 20 21:02:34 volumipi volumio[913]: info: CoreStateMachine::syncState currentStatus stop Oct 20 21:02:34 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:34 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:34 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:34 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:34 volumipi volumio[913]: info: ------------------------------ 369ms Oct 20 21:02:34 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:34 volumipi volumio[913]: info: CorePlayQueue::getTrack 1 Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:02:34 volumipi volumio[913]: info: CoreCommandRouter::Close All Modals sent Oct 20 21:02:34 volumipi volumio[913]: info: sendMpdCommand clear took 388 milliseconds Oct 20 21:02:34 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" Oct 20 21:02:34 volumipi volumio[913]: error: updateQueue error: Error: [50@0] {add} No such directory Oct 20 21:02:34 volumipi volumio[913]: info: ------------------------------ 405ms Oct 20 21:02:36 volumipi volumio[913]: info: Executing endpoint metavolumio Oct 20 21:02:36 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 20 21:02:36 volumipi volumio[913]: info: Executing endpoint metavolumio Oct 20 21:02:36 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 20 21:02:36 volumipi volumio[913]: info: Executing endpoint metavolumio Oct 20 21:02:36 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 20 21:02:39 volumipi volumio[913]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::addQueueItems Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::addQueueItems Oct 20 21:02:39 volumipi volumio[913]: info: Preload queue cleared Oct 20 21:02:39 volumipi volumio[913]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/No file found Oct 20 21:02:39 volumipi volumio[913]: info: Exploding uri INTERNAL/FusionDsp/tools/No file found in service mpd Oct 20 21:02:39 volumipi volumio[913]: error: scanFolder - failure to stat '/mnt/INTERNAL/FusionDsp/tools/No file found' Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioClearQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:39 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:39 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::updateTrackBlock Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::getTrackBlock Oct 20 21:02:39 volumipi volumio[913]: info: CoreCommandRouter::volumioPlay Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::play index 0 Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::play index undefined Oct 20 21:02:39 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:39 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:40 volumipi volumio[913]: info: CoreCommandRouter::volumioPlay Oct 20 21:02:40 volumipi volumio[913]: info: CoreStateMachine::play index undefined Oct 20 21:02:40 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:40 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:43 volumipi volumio[913]: info: MPD Database updated - AlbumList cache refreshed Oct 20 21:02:43 volumipi volumio[913]: info: Oct 20 21:02:43 volumipi volumio[913]: ---------------------------- MPD announces state update: database Oct 20 21:02:43 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 20 21:02:43 volumipi volumio[913]: info: Oct 20 21:02:43 volumipi volumio[913]: ---------------------------- MPD announces state update: update Oct 20 21:02:43 volumipi volumio[913]: info: ControllerMpd::getState Oct 20 21:02:43 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 20 21:02:43 volumipi volumio[913]: info: ControllerMpd::getState Oct 20 21:02:43 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 20 21:02:43 volumipi volumio[913]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" took 8445 milliseconds Oct 20 21:02:43 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand play Oct 20 21:02:43 volumipi volumio[913]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::addQueueItems Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::addQueueItems Oct 20 21:02:43 volumipi volumio[913]: info: Preload queue cleared Oct 20 21:02:43 volumipi volumio[913]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/No file found Oct 20 21:02:43 volumipi volumio[913]: info: Using cached record of: INTERNAL/FusionDsp/tools/No file found Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioClearQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::ClearQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::clearPlayQueue Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:43 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:43 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioPushQueue Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::saveQueue Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::updateTrackBlock Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::getTrackBlock Oct 20 21:02:43 volumipi volumio[913]: info: CoreCommandRouter::volumioPlay Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::play index 0 Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::stop Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::play index undefined Oct 20 21:02:43 volumipi volumio[913]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 21:02:43 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: error: Failed request for metavolumio API Oct 20 21:02:44 volumipi volumio[913]: info: sendMpdCommand status took 1624 milliseconds Oct 20 21:02:44 volumipi volumio[913]: info: sendMpdCommand status took 1623 milliseconds Oct 20 21:02:44 volumipi volumio[913]: info: sendMpdCommand status took 1624 milliseconds Oct 20 21:02:44 volumipi volumio[913]: verbose: ControllerMpd::parseState Oct 20 21:02:44 volumipi volumio[913]: info: Command Router : Notfying DB Updatefalse Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::Close All Modals sent Oct 20 21:02:44 volumipi volumio[913]: verbose: ControllerMpd::parseState Oct 20 21:02:44 volumipi volumio[913]: verbose: ControllerMpd::parseState Oct 20 21:02:44 volumipi volumio[913]: info: ControllerMpd::pushState Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::servicePushState Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:44 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:44 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 20 21:02:44 volumipi volumio[913]: verbose: CURRENT POSITION 0 Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::syncState stateService stop Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::syncState currentStatus stop Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:44 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:44 volumipi volumio[913]: info: No code Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:44 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:44 volumipi volumio[913]: info: ControllerMpd::pushState Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::servicePushState Oct 20 21:02:44 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 20 21:02:44 volumipi volumio[913]: verbose: CURRENT POSITION 0 Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::syncState stateService null Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::syncState currentStatus stop Oct 20 21:02:44 volumipi volumio[913]: info: CoreStateMachine::pushState Oct 20 21:02:44 volumipi volumio[913]: info: CorePlayQueue::getTrack 0 Oct 20 21:02:44 volumipi volumio[913]: info: CoreCommandRouter::volumioPushState Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output update for this device Oct 20 21:02:44 volumipi volumio[913]: info: MRS: Pushing multiroomSync output Oct 20 21:02:44 volumipi volumio[913]: info: ------------------------------ 1778ms Oct 20 21:02:44 volumipi volumio[913]: info: ------------------------------ 1779ms Oct 20 21:02:44 volumipi volumio[913]: info: sendMpdCommand play took 1764 milliseconds Oct 20 21:02:52 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 21:02:52 volumipi volumio[913]: info: CURURI: music-library Oct 20 21:03:00 volumipi volumio[913]: info: MRS: Found cast device: BRAVIA-4K-GB-ATV3-7a26d15c6dc05ef94d0c336e50bc4f53 Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:03:22 volumipi volumio[913]: info: Retrieving Cloud Streaming UI Oct 20 21:03:22 volumipi volumio[913]: info: Getting Tidal Cloud Configuration Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:03:22 volumipi volumio[913]: info: Getting Qobuz Cloud Configuration Oct 20 21:03:22 volumipi volumio[913]: info: Asking plugin for UI Config Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:03:22 volumipi volumio[913]: info: Getting Spotify Cloud Configuration Oct 20 21:03:22 volumipi volumio[913]: info: Asking plugin for UI Config Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:03:22 volumipi volumio[913]: info: Saving Spotify Acccount Oct 20 21:03:22 volumipi volumio[913]: info: Got it Oct 20 21:03:22 volumipi volumio[913]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 20 21:03:22 volumipi volumio[913]: info: Got Tidal Cloud Configuration Oct 20 21:03:22 volumipi volumio[913]: info: Got it Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::volumioGetBrowseSources Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::volumioGetBrowseSources Oct 20 21:03:22 volumipi volumio[913]: info: CoreCommandRouter::volumioGetBrowseSources Oct 20 21:03:23 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 20 21:03:23 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 20 21:03:23 volumipi volumio[913]: info: Preload queue cleared Oct 20 21:03:25 volumipi volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 20 21:03:25 volumipi volumio[913]: verbose: ControllerMpd::sendMpdCommand update Oct 20 21:03:25 volumipi volumio[913]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 21:03:25 volumipi volumio[913]: TypeError: Cannot read property 'substring' of undefined Oct 20 21:03:25 volumipi volumio[913]: at /volumio/app/plugins/music_service/mpd/index.js:2826:70 Oct 20 21:03:25 volumipi volumio[913]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 20 21:03:25 volumipi volumio[913]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 20 21:03:25 volumipi volumio[913]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 20 21:03:25 volumipi volumio[913]: at Socket.emit (events.js:315:20) Oct 20 21:03:25 volumipi volumio[913]: at addChunk (internal/streams/readable.js:309:12) Oct 20 21:03:25 volumipi volumio[913]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 20 21:03:25 volumipi volumio[913]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 20 21:03:25 volumipi volumio[913]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 20 21:03:25 volumipi volumio[913]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 21:03:26 volumipi sudo[9070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-20 21:02 Oct 20 21:03:26 volumipi sudo[9070]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"