-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-02-17 18:04:40 CET. -- Feb 17 18:03:00 volumio volumio[809]: info: Preload queue cleared Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::ClearQueue Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::stop Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::updateTrackBlock Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrackBlock Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::stPlaybackTimer Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 0 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::serviceStop Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 0 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::serviceStop Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::stop Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::clearPlayQueue Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::saveQueue Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::addQueueItems Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::addQueueItems Feb 17 18:03:00 volumio volumio[809]: info: Preload queue cleared Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/01-Cluster One.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/01-Cluster One.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/02-What Do You Want From Me.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/02-What Do You Want From Me.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::saveQueue Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::updateTrackBlock Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrackBlock Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPlay Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::play index 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::addQueueItems Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::addQueueItems Feb 17 18:03:00 volumio volumio[809]: info: Preload queue cleared Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/04-Marooned.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/04-Marooned.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/05-A Great Day For Freedom.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/05-A Great Day For Freedom.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/06-Wearing The Inside Out.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/06-Wearing The Inside Out.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/07-Take It Back.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/07-Take It Back.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/08-Coming Back To Life.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/08-Coming Back To Life.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/09-Keep Talking.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/09-Keep Talking.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/10-Lost For Words.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/10-Lost For Words.flac Feb 17 18:03:00 volumio volumio[809]: info: Adding Item to queue: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/11-High Hopes.flac Feb 17 18:03:00 volumio volumio[809]: info: Using cached record of: music-library/NAS/NAS/P/Pink Floyd/1994 - The Division Bell/11-High Hopes.flac Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::stop Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::saveQueue Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::play index undefined Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::updateTrackBlock Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrackBlock Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand stop took 43 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand stop took 24 milliseconds Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces system playlist update Feb 17 18:03:00 volumio volumio[809]: info: Ignoring MPD Status Update Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand clear took 5 milliseconds Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac" Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces system playlist update Feb 17 18:03:00 volumio volumio[809]: info: Ignoring MPD Status Update Feb 17 18:03:00 volumio volumio[809]: error: updateQueue error: null Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces system playlist update Feb 17 18:03:00 volumio volumio[809]: info: Ignoring MPD Status Update Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 4ms Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand add "NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac" took 3 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 2ms Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand play Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces system playlist update Feb 17 18:03:00 volumio volumio[809]: info: Ignoring MPD Status Update Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 4ms Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand play took 3 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 3ms Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces state update: player Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::getState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces state update: player Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::getState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:03:00 volumio volumio[809]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Feb 17 18:03:00 volumio volumio[809]: info: FusionDsp - ---- read samplerate from file: 96000 Feb 17 18:03:00 volumio volumio[809]: info: camilladsp stopping service pid 6475... Feb 17 18:03:00 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Feb 17 18:03:00 volumio volumio[809]: info: camilladsp service terminated, instance 1 Feb 17 18:03:00 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:03:00 volumio volumio[809]: info: camilladsp service started and running in background, instance 1 Feb 17 18:03:00 volumio volumio[809]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Feb 17 18:03:00 volumio volumio[809]: info: FusionDsp - ---- read samplerate from file: 96000 Feb 17 18:03:00 volumio volumio[809]: info: camilladsp stopping service pid 6488... Feb 17 18:03:00 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Feb 17 18:03:00 volumio volumio[809]: info: camilladsp service terminated, instance 2 Feb 17 18:03:00 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:03:00 volumio volumio[809]: info: camilladsp service started and running in background, instance 2 Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces state update: player Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand status took 86 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand status took 85 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::getState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:03:00 volumio volumio[809]: info: Feb 17 18:03:00 volumio volumio[809]: ---------------------------- MPD announces state update: player Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::getState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:03:00 volumio volumio[809]: info: camilladsp spawned new process with pid 6642, instance 2, run: true Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand status took 35 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand status took 32 milliseconds Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseState Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::pushState Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::servicePushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":424,"samplerate":"96 kHz","bitdepth":"24 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poles Apart","artist":"Pink Floyd","album":"The Division Bell","uri":"NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac","trackType":"flac"} Feb 17 18:03:00 volumio volumio[809]: verbose: CURRENT POSITION 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState stateService play Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::pushState Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::servicePushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":424,"samplerate":"96 kHz","bitdepth":"24 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poles Apart","artist":"Pink Floyd","album":"The Division Bell","uri":"NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac","trackType":"flac"} Feb 17 18:03:00 volumio volumio[809]: verbose: CURRENT POSITION 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState stateService play Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:03:00 volumio volumio[809]: info: Received an update from plugin. extracting info from payload Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 148ms Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 163ms Feb 17 18:03:00 volumio volumio[809]: info: camilladsp spawned new process with pid 6649, instance 1, run: true Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 17 18:03:00 volumio volumio[809]: info: sendMpdCommand playlistinfo took 69 milliseconds Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:03:00 volumio volumio[809]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::pushState Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::servicePushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: verbose: STATE SERVICE {"status":"play","position":0,"seek":622,"duration":424,"samplerate":"96 kHz","bitdepth":"24 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"6722 Kbps","isStreaming":false,"title":"Poles Apart","artist":"Pink Floyd","album":"The Division Bell","uri":"NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac","trackType":"flac"} Feb 17 18:03:00 volumio volumio[809]: verbose: CURRENT POSITION 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState stateService play Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:03:00 volumio volumio[809]: info: Received an update from plugin. extracting info from payload Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: ControllerMpd::pushState Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::servicePushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: verbose: STATE SERVICE {"status":"play","position":0,"seek":622,"duration":424,"samplerate":"96 kHz","bitdepth":"24 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"6722 Kbps","isStreaming":false,"title":"Poles Apart","artist":"Pink Floyd","album":"The Division Bell","uri":"NAS/NAS/P/Pink Floyd/1994 - The Division Bell/03-Poles Apart.flac","trackType":"flac"} Feb 17 18:03:00 volumio volumio[809]: verbose: CURRENT POSITION 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState stateService play Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:03:00 volumio volumio[809]: info: Received an update from plugin. extracting info from payload Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:00 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:00 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 137ms Feb 17 18:03:00 volumio volumio[809]: info: ------------------------------ 133ms Feb 17 18:03:01 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:03:01 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:01 volumio volumio[809]: info: Listing playlists Feb 17 18:03:11 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:03:11 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:15 volumio volumio[809]: info: VolumeController::SetAlsaVolume50 Feb 17 18:03:15 volumio volumio[809]: info: CoreStateMachine::pushState Feb 17 18:03:15 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:15 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:03:15 volumio volumio[809]: info: CoreCommandRouter::volumioPushState Feb 17 18:03:17 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:03:21 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:03:21 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:21 volumio volumio[809]: info: Listing playlists Feb 17 18:03:21 volumio volumio[809]: info: Listing playlists Feb 17 18:03:25 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Feb 17 18:03:25 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Feb 17 18:03:25 volumio volumio[809]: error: FusionDsp - File size not found in array! Feb 17 18:03:25 volumio volumio[809]: info: FusionDsp - Nulleq applied Feb 17 18:03:25 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:03:25 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:03:25 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:03:29 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Feb 17 18:03:29 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Feb 17 18:03:29 volumio volumio[809]: info: FusionDsp - Launching CamillaDsp GUI Feb 17 18:03:29 volumio volumio[809]: info: FusionDsp loaded Feb 17 18:03:29 volumio sudo[6749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 17 18:03:29 volumio sudo[6749]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:03:29 volumio sudo[6749]: pam_unix(sudo:session): session closed for user root Feb 17 18:03:29 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:03:29 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:03:29 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:03:29 volumio volumio[809]: info: FusionDsp - IP adress is ---------------------------127.0.0.1 Feb 17 18:03:29 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:03:31 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:03:31 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:34 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Feb 17 18:03:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Feb 17 18:03:34 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:03:34 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:03:34 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:03:35 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:03:41 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:03:41 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:03:41 volumio volumio[809]: info: Listing playlists Feb 17 18:03:41 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Feb 17 18:03:41 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Feb 17 18:03:41 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:03:41 volumio volumio[809]: info: FusionDsp - Effects disabled Feb 17 18:03:41 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:03:41 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:03:41 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:03:43 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp moresettings Feb 17 18:03:43 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Feb 17 18:03:43 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:03:43 volumio volumio[809]: info: FusionDsp - Effects disabled Feb 17 18:03:43 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:03:43 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:03:44 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:03:51 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:03:51 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:04:01 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:04:01 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:04:01 volumio volumio[809]: info: Listing playlists Feb 17 18:04:01 volumio volumio[809]: info: Listing playlists Feb 17 18:04:11 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:04:11 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:04:12 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Feb 17 18:04:12 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Feb 17 18:04:12 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:04:12 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:04:12 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:04:12 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:04:15 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Feb 17 18:04:15 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Feb 17 18:04:15 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:04:15 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:04:15 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:04:15 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:04:19 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Feb 17 18:04:19 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Feb 17 18:04:19 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:04:19 volumio volumio[809]: info: FusionDsp - Effects disabled Feb 17 18:04:19 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:04:19 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:04:19 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:04:21 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:04:21 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:04:21 volumio volumio[809]: info: Listing playlists Feb 17 18:04:21 volumio volumio[809]: info: Listing playlists Feb 17 18:04:21 volumio volumio[809]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 17 18:04:21 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 17 18:04:22 volumio volumio[809]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 17 18:04:22 volumio volumio[809]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 17 18:04:22 volumio volumio[809]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:04:22 volumio volumio[809]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:04:25 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:04:25 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:04:28 volumio volumio[809]: info: Executing endpoint metavolumio Feb 17 18:04:28 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 18:04:28 volumio volumio[809]: info: Executing endpoint metavolumio Feb 17 18:04:28 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 18:04:28 volumio volumio[809]: info: Executing endpoint metavolumio Feb 17 18:04:28 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 18:04:28 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 18:04:28 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 17 18:04:29 volumio volumio[809]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Feb 17 18:04:31 volumio volumio[809]: info: CoreCommandRouter::volumioGetState Feb 17 18:04:31 volumio volumio[809]: info: CorePlayQueue::getTrack 2 Feb 17 18:04:34 volumio volumio[809]: info: FusionDsp - Stopping FusionDsp service Feb 17 18:04:34 volumio volumio[809]: info: camilladsp stopping service pid 6642... Feb 17 18:04:34 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Feb 17 18:04:34 volumio volumio[809]: info: camilladsp service terminated, instance 2 Feb 17 18:04:34 volumio volumio[809]: info: Disabling plugin fusiondsp Feb 17 18:04:34 volumio sudo[6912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Feb 17 18:04:34 volumio sudo[6912]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:34 volumio volumio[809]: info: Preparing to generate the ALSA configuration file Feb 17 18:04:34 volumio systemd[1]: Stopping FusionDsp Daemon... Feb 17 18:04:34 volumio volumio[6470]: Backend configuration: Feb 17 18:04:34 volumio volumio[6470]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Feb 17 18:04:34 volumio volumio[6470]: active_config_txt: null Feb 17 18:04:34 volumio volumio[6470]: camilla_host: 127.0.0.1 Feb 17 18:04:34 volumio volumio[6470]: camilla_port: 9876 Feb 17 18:04:34 volumio volumio[6470]: coeff_dir: /data/INTERNAL/FusionDsp/filters Feb 17 18:04:34 volumio volumio[6470]: config_dir: /data/configuration/audio_interface/fusiondsp Feb 17 18:04:34 volumio volumio[6470]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Feb 17 18:04:34 volumio volumio[6470]: log_file: /tmp/camilladsp.log Feb 17 18:04:34 volumio volumio[6470]: on_get_active_config: null Feb 17 18:04:34 volumio volumio[6470]: on_set_active_config: null Feb 17 18:04:34 volumio volumio[6470]: port: 5011 Feb 17 18:04:34 volumio volumio[6470]: supported_capture_types: null Feb 17 18:04:34 volumio volumio[6470]: supported_playback_types: null Feb 17 18:04:34 volumio volumio[6470]: update_config_symlink: false Feb 17 18:04:34 volumio volumio[6470]: update_config_txt: false Feb 17 18:04:34 volumio volumio[6470]: ======== Running on http://0.0.0.0:5011 ======== Feb 17 18:04:34 volumio volumio[6470]: (Press CTRL+C to quit) Feb 17 18:04:34 volumio volumio[809]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 17 18:04:34 volumio volumio[809]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 17 18:04:34 volumio volumio[809]: info: Reading ALSA contributions from plugins. Feb 17 18:04:34 volumio volumio[809]: info: Asound.conf file written Feb 17 18:04:34 volumio sudo[6915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 17 18:04:34 volumio sudo[6915]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:34 volumio sudo[6915]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:34 volumio systemd[1]: fusiondsp.service: Succeeded. Feb 17 18:04:34 volumio systemd[1]: Stopped FusionDsp Daemon. Feb 17 18:04:34 volumio sudo[6912]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:34 volumio volumio[809]: info: Output device has changed, restarting MPD Feb 17 18:04:34 volumio sudo[6920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 18:04:34 volumio sudo[6920]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:34 volumio sudo[6920]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:34 volumio volumio[809]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio sudo[6923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 18:04:34 volumio sudo[6923]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:34 volumio volumio[809]: info: Done. Feb 17 18:04:34 volumio systemd[1]: Stopping Music Player Daemon... Feb 17 18:04:34 volumio volumio[809]: info: MPD Permissions set Feb 17 18:04:34 volumio volumio[809]: info: FusionDsp - Reporting Fusion DSP Disabled Feb 17 18:04:34 volumio volumio[809]: info: Removing fusiondspeq DSP Signal Path Element Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:04:34 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:34 volumio systemd[1]: mpd.service: Succeeded. Feb 17 18:04:34 volumio systemd[1]: Stopped Music Player Daemon. Feb 17 18:04:34 volumio systemd[1]: Starting Music Player Daemon... Feb 17 18:04:34 volumio sudo[6931]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 18:04:34 volumio sudo[6931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:34 volumio sudo[6931]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6934, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6941, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6948, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6955, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6962, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6969, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6976, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6983, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6990, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 6997, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7004, instance 1, run: true Feb 17 18:04:35 volumio mpd[6933]: Feb 17 18:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7018, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7032, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7039, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7046, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7053, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7060, instance 1, run: true Feb 17 18:04:35 volumio systemd[1]: Started Music Player Daemon. Feb 17 18:04:35 volumio sudo[6923]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7069, instance 1, run: true Feb 17 18:04:35 volumio volumio[809]: error: updateQueue error: null Feb 17 18:04:35 volumio volumio[809]: info: camilladsp spawned new process with pid 7076, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7083, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7090, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7097, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7104, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7111, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7118, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7125, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7132, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7139, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7146, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7153, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7160, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7167, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7174, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7181, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7188, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7195, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7202, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7209, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7216, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7223, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7230, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7237, instance 1, run: true Feb 17 18:04:36 volumio volumio[809]: info: camilladsp spawned new process with pid 7244, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7251, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7258, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7265, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7272, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7279, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7286, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7293, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7300, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7307, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7314, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7321, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7328, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7335, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7342, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7349, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7356, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7363, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7370, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7377, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7384, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7391, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7398, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7405, instance 1, run: true Feb 17 18:04:37 volumio volumio[809]: info: camilladsp spawned new process with pid 7412, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7419, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7426, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7433, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7440, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7447, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7454, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7461, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7468, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7475, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7482, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7489, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7496, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7503, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7510, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7517, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7524, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7531, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7538, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7545, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7552, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7559, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7566, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7573, instance 1, run: true Feb 17 18:04:38 volumio volumio[809]: info: camilladsp spawned new process with pid 7580, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7587, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7594, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7601, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7608, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7615, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7622, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7629, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7636, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7643, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7650, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7657, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7665, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7672, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7679, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7686, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7693, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7700, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: Starting Uninstall of plugin audio_interface - fusiondsp Feb 17 18:04:39 volumio volumio[809]: info: Uninstalling plugin fusiondsp Feb 17 18:04:39 volumio volumio[809]: info: Disabling plugin fusiondsp Feb 17 18:04:39 volumio volumio[809]: info: Preparing to generate the ALSA configuration file Feb 17 18:04:39 volumio volumio[809]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 17 18:04:39 volumio volumio[809]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 17 18:04:39 volumio volumio[809]: info: Reading ALSA contributions from plugins. Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7707, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: Asound.conf file unchanged, so no further update is needed Feb 17 18:04:39 volumio volumio[809]: info: Output device has changed, restarting MPD Feb 17 18:04:39 volumio sudo[7715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 18:04:39 volumio sudo[7715]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:39 volumio sudo[7715]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:39 volumio volumio[809]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio sudo[7718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 18:04:39 volumio sudo[7718]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:39 volumio volumio[809]: info: Checking if uninstall.sh is present Feb 17 18:04:39 volumio systemd[1]: Stopping Music Player Daemon... Feb 17 18:04:39 volumio volumio[809]: info: MPD Permissions set Feb 17 18:04:39 volumio volumio[809]: info: Executing uninstall.sh Feb 17 18:04:39 volumio systemd[1]: mpd.service: Succeeded. Feb 17 18:04:39 volumio systemd[1]: Stopped Music Player Daemon. Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7725, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio sudo[7724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Feb 17 18:04:39 volumio sudo[7724]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:39 volumio systemd[1]: Starting Music Player Daemon... Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7740, instance 1, run: true Feb 17 18:04:39 volumio sudo[7747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Feb 17 18:04:39 volumio sudo[7747]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:39 volumio sudo[7747]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:39 volumio sudo[7724]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:39 volumio volumio[809]: info: camilladsp spawned new process with pid 7749, instance 1, run: true Feb 17 18:04:39 volumio volumio[809]: info: Uninstall script completed Feb 17 18:04:40 volumio sudo[7726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 18:04:40 volumio sudo[7726]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:04:40 volumio volumio[809]: info: Removing plugin fusiondsp from configuration Feb 17 18:04:40 volumio sudo[7726]: pam_unix(sudo:session): session closed for user root Feb 17 18:04:40 volumio volumio[809]: info: Successfully removed fusiondsp configuration files Feb 17 18:04:40 volumio volumio[809]: info: Plugin folders cleanup Feb 17 18:04:40 volumio volumio[809]: info: Scanning into folder /volumio/app/plugins/ Feb 17 18:04:40 volumio volumio[809]: info: Scanning category audio_interface Feb 17 18:04:40 volumio volumio[809]: info: Scanning category miscellanea Feb 17 18:04:40 volumio volumio[809]: info: Scanning category music_service Feb 17 18:04:40 volumio volumio[809]: info: Scanning category plugins.json Feb 17 18:04:40 volumio volumio[809]: info: Scanning category system_controller Feb 17 18:04:40 volumio volumio[809]: info: Scanning category user_interface Feb 17 18:04:40 volumio volumio[809]: info: Scanning into folder /data/plugins/ Feb 17 18:04:40 volumio volumio[809]: info: Scanning category audio_interface Feb 17 18:04:40 volumio volumio[809]: info: Cleaning folder for fusiondsp Feb 17 18:04:40 volumio volumio[809]: info: Scanning category music_service Feb 17 18:04:40 volumio volumio[809]: info: Plugin folders cleanup completed Feb 17 18:04:40 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 18:04:40 volumio volumio[809]: info: [{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.2.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}] Feb 17 18:04:40 volumio volumio[809]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 17 18:04:40 volumio volumio[809]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Feb 17 18:04:40 volumio volumio[809]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:04:40 volumio volumio[809]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Feb 17 18:04:40 volumio volumio[809]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Feb 17 18:04:40 volumio volumio[809]: at onErrorNT (internal/child_process.js:465:16) Feb 17 18:04:40 volumio volumio[809]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Feb 17 18:04:40 volumio volumio[809]: errno: -2, Feb 17 18:04:40 volumio volumio[809]: code: 'ENOENT', Feb 17 18:04:40 volumio volumio[809]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Feb 17 18:04:40 volumio volumio[809]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Feb 17 18:04:40 volumio volumio[809]: spawnargs: [ Feb 17 18:04:40 volumio volumio[809]: '-p', Feb 17 18:04:40 volumio volumio[809]: 9876, Feb 17 18:04:40 volumio volumio[809]: '-o', Feb 17 18:04:40 volumio volumio[809]: '/tmp/camilladsp.log', Feb 17 18:04:40 volumio volumio[809]: '-l', Feb 17 18:04:40 volumio volumio[809]: 'warn', Feb 17 18:04:40 volumio volumio[809]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Feb 17 18:04:40 volumio volumio[809]: ] Feb 17 18:04:40 volumio volumio[809]: } Feb 17 18:04:40 volumio volumio[809]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:04:40 volumio mpd[7757]: Feb 17 18:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 18:04:40 volumio sudo[7772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-17 18:03 Feb 17 18:04:40 volumio sudo[7772]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"