-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sun 2025-12-28 14:40:26 GMT. -- Dec 28 14:39:10 volumio-hifi nmbd[749]: [2025/12/28 14:39:10.532851, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 28 14:39:10 volumio-hifi nmbd[749]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.186 for name WORKGROUP<1d>. Dec 28 14:39:10 volumio-hifi nmbd[749]: This response was from IP 192.168.1.251, reporting an IP address of 192.168.1.251. Dec 28 14:39:12 volumio-hifi volumio[963]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::ClearQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::updateTrackBlock Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrackBlock Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 5 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 5 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::serviceStop Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 5 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::serviceStop Dec 28 14:39:12 volumio-hifi volumio[963]: info: [1766932752809] ControllerQobuz::stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::stop Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::clearPlayQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::saveQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::addQueueItems Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::addQueueItems Dec 28 14:39:12 volumio-hifi volumio[963]: info: Preload queue cleared Dec 28 14:39:12 volumio-hifi volumio[963]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Dec 28 14:39:12 volumio-hifi volumio[963]: info: Exploding uri INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav in service mpd Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioClearQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::ClearQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::clearPlayQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::saveQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 5 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: ALBUMART /albumart?cacheid=48&web=FusionDsp/Test%20Signal/extralarge&path=%2Fmnt%2FINTERNAL%2FFusionDsp%2Ftools%2F512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav&metadata=false Dec 28 14:39:12 volumio-hifi volumio[963]: info: URI /mnt/INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand stop took 44 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::saveQueue Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::updateTrackBlock Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrackBlock Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPlay Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::play index 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::play index undefined Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::startPlaybackTimer Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand stop took 7 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:12 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand clear took 10 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:12 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:12 volumio-hifi volumio[963]: error: updateQueue error: null Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:12 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:12 volumio-hifi volumio[963]: info: ------------------------------ 12ms Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" took 9 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: ------------------------------ 8ms Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand play Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:12 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:12 volumio-hifi volumio[963]: info: ------------------------------ 22ms Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand play took 19 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:12 volumio-hifi volumio[963]: info: ------------------------------ 5ms Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:12 volumio-hifi volumio[963]: info: FusionDsp - ---- read samplerate, raw: Dec 28 14:39:12 volumio-hifi volumio[963]: error: FusionDsp - invalid sample rate Dec 28 14:39:12 volumio-hifi volumio[963]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 14:39:12 volumio-hifi volumio[963]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 14:39:12 volumio-hifi volumio[963]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand status took 43 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand status took 33 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:12 volumio-hifi volumio[963]: info: Dec 28 14:39:12 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand status took 5 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: info: sendMpdCommand status took 2 milliseconds Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus stop Dec 28 14:39:12 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:12 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus play Dec 28 14:39:12 volumio-hifi volumio[963]: info: Received an update from plugin. extracting info from payload Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:12 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:12 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:12 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:12 volumio-hifi volumio[963]: info: ------------------------------ 70ms Dec 28 14:39:12 volumio-hifi volumio[963]: info: ------------------------------ 67ms Dec 28 14:39:13 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 28 14:39:13 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 28 14:39:13 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:13 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:13 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:13 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus play Dec 28 14:39:13 volumio-hifi volumio[963]: info: Received an update from plugin. extracting info from payload Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:13 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus play Dec 28 14:39:13 volumio-hifi volumio[963]: info: Received an update from plugin. extracting info from payload Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:13 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:13 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:13 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:13 volumio-hifi volumio[963]: info: ------------------------------ 76ms Dec 28 14:39:13 volumio-hifi volumio[963]: info: ------------------------------ 73ms Dec 28 14:39:22 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:22 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 1 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::pause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::servicePause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePause Dec 28 14:39:25 volumio-hifi volumio[963]: info: ControllerMpd::pause Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand pause Dec 28 14:39:25 volumio-hifi volumio[963]: info: Dec 28 14:39:25 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:25 volumio-hifi volumio[963]: info: sendMpdCommand pause took 2 milliseconds Dec 28 14:39:25 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:25 volumio-hifi volumio[963]: info: Dec 28 14:39:25 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:25 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:25 volumio-hifi volumio[963]: info: sendMpdCommand status took 3 milliseconds Dec 28 14:39:25 volumio-hifi volumio[963]: info: sendMpdCommand status took 1 milliseconds Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:25 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 14:39:25 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:25 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1715,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService pause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus pause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:25 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:39:25 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1715,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:25 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService pause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus pause Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:25 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:25 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:25 volumio-hifi volumio[963]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:39:25 volumio-hifi volumio[963]: info: ------------------------------ 32ms Dec 28 14:39:25 volumio-hifi volumio[963]: info: ------------------------------ 31ms Dec 28 14:39:37 volumio-hifi volumio[963]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Dec 28 14:39:37 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Dec 28 14:39:37 volumio-hifi volumio[963]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 14:39:37 volumio-hifi volumio[963]: info: FusionDsp - Nulleq applied Dec 28 14:39:37 volumio-hifi volumio[963]: error: FusionDsp - WebSocket error: [object Object] Dec 28 14:39:37 volumio-hifi volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 14:39:37 volumio-hifi volumio[963]: info: CoreCommandRouter::Close All Modals sent Dec 28 14:39:37 volumio-hifi volumio[963]: info: FusionDsp - Choose a preset Dec 28 14:39:37 volumio-hifi volumio[963]: info: FusionDsp - Dec 28 14:39:56 volumio-hifi volumio[963]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::ClearQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::updateTrackBlock Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrackBlock Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::stPlaybackTimer Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::serviceStop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::serviceStop Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::stop Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::clearPlayQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::saveQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::addQueueItems Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::addQueueItems Dec 28 14:39:56 volumio-hifi volumio[963]: info: Preload queue cleared Dec 28 14:39:56 volumio-hifi volumio[963]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Dec 28 14:39:56 volumio-hifi volumio[963]: info: Using cached record of: INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioClearQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::ClearQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::clearPlayQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::saveQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::saveQueue Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::updateTrackBlock Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrackBlock Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPlay Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::play index 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::play index undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::startPlaybackTimer Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand stop took 49 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand stop took 21 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:56 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand status took 6 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand clear took 4 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand status took 4 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: 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} Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: No code Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 32ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:56 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:56 volumio-hifi volumio[963]: error: updateQueue error: null Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:56 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 35ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/512kMeasSweep_30_to_20000_44k_PCM16_LR_refL.wav" took 33 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 6ms Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand play Dec 28 14:39:56 volumio-hifi volumio[963]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 45ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces system playlist update Dec 28 14:39:56 volumio-hifi volumio[963]: info: Ignoring MPD Status Update Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 34ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand play took 31 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 30ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:56 volumio-hifi volumio[963]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 14:39:56 volumio-hifi volumio[963]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 14:39:56 volumio-hifi volumio[963]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 14:39:56 volumio-hifi volumio[963]: info: FusionDsp - Nulleq applied Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand status took 13 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand status took 13 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand status took 11 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:56 volumio-hifi volumio[963]: info: Dec 28 14:39:56 volumio-hifi volumio[963]: ---------------------------- MPD announces state update: player Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::getState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand status Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand status took 7 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseState Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus stop Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus play Dec 28 14:39:56 volumio-hifi volumio[963]: info: Received an update from plugin. extracting info from payload Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus play Dec 28 14:39:56 volumio-hifi volumio[963]: info: Received an update from plugin. extracting info from payload Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 49ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 62ms Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 60ms Dec 28 14:39:56 volumio-hifi volumio[963]: error: FusionDsp - WebSocket error: [object Object] Dec 28 14:39:56 volumio-hifi volumio[963]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: ControllerMpd::parseTrackInfo Dec 28 14:39:56 volumio-hifi volumio[963]: info: ControllerMpd::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::servicePushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":16,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"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"} Dec 28 14:39:56 volumio-hifi volumio[963]: verbose: CURRENT POSITION 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState stateService play Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::syncState currentStatus play Dec 28 14:39:56 volumio-hifi volumio[963]: info: Received an update from plugin. extracting info from payload Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreStateMachine::pushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioPushState Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output update for this device Dec 28 14:39:56 volumio-hifi volumio[963]: info: MRS: Pushing multiroomSync output Dec 28 14:39:56 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:39:56 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:39:56 volumio-hifi volumio[963]: info: ------------------------------ 72ms Dec 28 14:40:06 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:06 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 1 Dec 28 14:40:12 volumio-hifi volumio[963]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Dec 28 14:40:12 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Dec 28 14:40:12 volumio-hifi volumio[963]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 14:40:12 volumio-hifi volumio[963]: info: FusionDsp - Nulleq applied Dec 28 14:40:12 volumio-hifi volumio[963]: info: FusionDsp - Effects disabled Dec 28 14:40:12 volumio-hifi volumio[963]: error: FusionDsp - WebSocket error: [object Object] Dec 28 14:40:12 volumio-hifi volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 14:40:12 volumio-hifi volumio[963]: info: CoreCommandRouter::Close All Modals sent Dec 28 14:40:12 volumio-hifi volumio[963]: info: FusionDsp - Choose a preset Dec 28 14:40:12 volumio-hifi volumio[963]: info: FusionDsp - Dec 28 14:40:20 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 14:40:20 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 14:40:20 volumio-hifi volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 14:40:20 volumio-hifi volumio[963]: info: FusionDsp - Choose a preset Dec 28 14:40:20 volumio-hifi volumio[963]: info: FusionDsp - Dec 28 14:40:23 volumio-hifi volumio[963]: info: Enabling plugin fusiondsp Dec 28 14:40:23 volumio-hifi volumio[963]: info: Loading plugin "fusiondsp"... Dec 28 14:40:23 volumio-hifi volumio[963]: info: Applying required configuration parameters for plugin fusiondsp Dec 28 14:40:23 volumio-hifi volumio[963]: info: Preparing to generate the ALSA configuration file Dec 28 14:40:23 volumio-hifi volumio[963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 28 14:40:23 volumio-hifi volumio[963]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 14:40:23 volumio-hifi volumio[963]: info: Reading ALSA contributions from plugins. Dec 28 14:40:23 volumio-hifi volumio[963]: info: Asound.conf file unchanged, so no further update is needed Dec 28 14:40:23 volumio-hifi volumio[963]: info: Output device has changed, restarting MPD Dec 28 14:40:23 volumio-hifi sudo[14849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 14:40:23 volumio-hifi sudo[14849]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:23 volumio-hifi volumio[963]: info: Output device has changed, restarting Shairport Sync Dec 28 14:40:23 volumio-hifi sudo[14849]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:23 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:23 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 14:40:23 volumio-hifi sudo[14852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 14:40:23 volumio-hifi sudo[14852]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:23 volumio-hifi systemd[1]: Stopping Music Player Daemon... Dec 28 14:40:23 volumio-hifi systemd[1]: mpd.service: Succeeded. Dec 28 14:40:23 volumio-hifi systemd[1]: Stopped Music Player Daemon. Dec 28 14:40:23 volumio-hifi volumio[963]: xcb_connection_has_error() returned true Dec 28 14:40:23 volumio-hifi volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 14:40:23 volumio-hifi volumio[963]: xcb_connection_has_error() returned true Dec 28 14:40:23 volumio-hifi volumio[963]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 14:40:23 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:23 volumio-hifi volumio[963]: info: QobuzConnect: setDeactiveState invoked Dec 28 14:40:23 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:40:23 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:23 volumio-hifi vtcs[13555]: [2025-12-28 14:40:23.950] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 28 14:40:23 volumio-hifi vtcs[13555]: [2025-12-28 14:40:23.952] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 28 14:40:23 volumio-hifi vtcs[13555]: [2025-12-28 14:40:23.952] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 28 14:40:23 volumio-hifi systemd[1]: Starting Music Player Daemon... Dec 28 14:40:23 volumio-hifi volumio[963]: info: PLUGIN START: fusiondsp Dec 28 14:40:23 volumio-hifi volumio[963]: info: Loading i18n strings for locale en Dec 28 14:40:23 volumio-hifi sudo[14861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 14:40:23 volumio-hifi sudo[14861]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: FusionDsp - mixtype--------------------- None Dec 28 14:40:24 volumio-hifi volumio[963]: info: Preparing to generate the ALSA configuration file Dec 28 14:40:24 volumio-hifi systemd[1]: Stopping Volumio Tidal Connect Service... Dec 28 14:40:24 volumio-hifi volumio[963]: info: Done. Dec 28 14:40:24 volumio-hifi systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 28 14:40:24 volumio-hifi systemd[1]: vtcs.service: Succeeded. Dec 28 14:40:24 volumio-hifi systemd[1]: Stopped Volumio Tidal Connect Service. Dec 28 14:40:24 volumio-hifi sudo[14861]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi sudo[14865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 14:40:24 volumio-hifi sudo[14865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi sudo[14857]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 14:40:24 volumio-hifi sudo[14857]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi sudo[14857]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi volumio[963]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 14:40:24 volumio-hifi volumio[963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 28 14:40:24 volumio-hifi volumio[963]: info: Reading ALSA contributions from plugins. Dec 28 14:40:24 volumio-hifi sudo[14873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 14:40:24 volumio-hifi sudo[14873]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:24 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:24 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 14:40:24 volumio-hifi volumio[963]: info: MPD Permissions set Dec 28 14:40:24 volumio-hifi sudo[14865]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi sudo[14873]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi sudo[14877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 14:40:24 volumio-hifi sudo[14877]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi volumio[963]: info: FusionDsp - Choose a preset Dec 28 14:40:24 volumio-hifi systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 28 14:40:24 volumio-hifi qobuz-connect[13504]: 20251228 14:40:24.302 [13504.13504] INFO SampleApp: Stopping Local configuration server Dec 28 14:40:24 volumio-hifi qobuz-connect[13504]: 20251228 14:40:24.313 [13504.13504] INFO SampleApp: shat down connection on UNIX socket Dec 28 14:40:24 volumio-hifi systemd[1]: qobuz-connect.service: Succeeded. Dec 28 14:40:24 volumio-hifi systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 28 14:40:24 volumio-hifi systemd[1]: Started Volumio Qobuz Connect Service. Dec 28 14:40:24 volumio-hifi sudo[14877]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: FusionDsp - Dec 28 14:40:24 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:24 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:24 volumio-hifi volumio[963]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 14:40:24 volumio-hifi volumio[963]: info: QobuzConnect: setDeactiveState invoked Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:40:24 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:24 volumio-hifi volumio[963]: info: Executing endpoint qc_getconfig Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.646 [14879.14879] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 14:40:24 volumio-hifi volumio[963]: info: Asound.conf file unchanged, so no further update is needed Dec 28 14:40:24 volumio-hifi volumio[963]: info: Output device has changed, restarting MPD Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.656 [14879.14879] INFO VolumeManager: [0x1920c80]: Setting new playback volume: 75 Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.656 [14879.14879] INFO VolumeManager: [0x1920c80]: Setting new mute state: 0 Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.656 [14879.14879] INFO QobuzConnect: [0x1921650]: Client initialized! Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.656 [14879.14879] INFO SampleApp: Starting Avahi advertising, name: Volumio-hifi, service name: _qobuz-connect._tcp Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.678 [14879.14879] INFO LocalConfigManager: [0x1920560]: Starting Local Configuration server Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.678 [14879.14879] INFO SampleApp: Starting Local configuration server Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.679 [14879.14879] INFO SampleApp: Connected to UNIX socket client 0x1915f60 Dec 28 14:40:24 volumio-hifi volumio[963]: info: Output device has changed, restarting Shairport Sync Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 14:40:24 volumio-hifi sudo[14889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 14:40:24 volumio-hifi sudo[14889]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi sudo[14889]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi sudo[14891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 14:40:24 volumio-hifi sudo[14891]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 28 14:40:24 volumio-hifi systemd[1]: mpd.service: Succeeded. Dec 28 14:40:24 volumio-hifi volumio[963]: xcb_connection_has_error() returned true Dec 28 14:40:24 volumio-hifi systemd[1]: Stopped Music Player Daemon. Dec 28 14:40:24 volumio-hifi volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 14:40:24 volumio-hifi volumio[963]: xcb_connection_has_error() returned true Dec 28 14:40:24 volumio-hifi volumio[963]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:24 volumio-hifi qobuz-connect[14879]: 20251228 14:40:24.795 [14879.14879] INFO SampleApp: Playback volume changed: 75 Dec 28 14:40:24 volumio-hifi volumio[963]: info: QobuzConnect: setDeactiveState invoked Dec 28 14:40:24 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:40:24 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:24 volumio-hifi systemd[1]: Starting Music Player Daemon... Dec 28 14:40:24 volumio-hifi sudo[14901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 14:40:24 volumio-hifi sudo[14901]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi sudo[14901]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi sudo[14904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 14:40:24 volumio-hifi sudo[14904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi sudo[14904]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:24 volumio-hifi sudo[14897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 14:40:24 volumio-hifi sudo[14897]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:24 volumio-hifi sudo[14897]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:25 volumio-hifi volumio[963]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 14:40:25 volumio-hifi volumio[963]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 14:40:25 volumio-hifi volumio[963]: info: Starting Shairport Sync Dec 28 14:40:25 volumio-hifi sudo[14913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 14:40:25 volumio-hifi sudo[14913]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:25 volumio-hifi sudo[14913]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:25 volumio-hifi sudo[14917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 14:40:25 volumio-hifi sudo[14917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:25 volumio-hifi sudo[14918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 14:40:25 volumio-hifi sudo[14918]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:40:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:25 volumio-hifi systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 28 14:40:25 volumio-hifi volumio[963]: info: MPD Permissions set Dec 28 14:40:25 volumio-hifi qobuz-connect[14879]: 20251228 14:40:25.128 [14879.14879] INFO SampleApp: Stopping Local configuration server Dec 28 14:40:25 volumio-hifi qobuz-connect[14879]: 20251228 14:40:25.138 [14879.14879] INFO SampleApp: shat down connection on UNIX socket Dec 28 14:40:25 volumio-hifi systemd[1]: qobuz-connect.service: Succeeded. Dec 28 14:40:25 volumio-hifi systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:25 volumio-hifi volumio[963]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 14:40:25 volumio-hifi volumio[963]: info: QobuzConnect: setDeactiveState invoked Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:40:25 volumio-hifi systemd[1]: Started Volumio Qobuz Connect Service. Dec 28 14:40:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:25 volumio-hifi sudo[14917]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:25 volumio-hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi systemd[1]: shairport-sync.service: Succeeded. Dec 28 14:40:25 volumio-hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 28 14:40:25 volumio-hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 28 14:40:25 volumio-hifi sudo[14918]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:25 volumio-hifi volumio[963]: info: Shairport-Sync Started Dec 28 14:40:25 volumio-hifi volumio[963]: info: Executing endpoint qc_getconfig Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 14:40:25 volumio-hifi volumio[963]: info: Starting Shairport Sync Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.392 [14921.14921] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.398 [14921.14921] INFO VolumeManager: [0x1206c80]: Setting new playback volume: 75 Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.398 [14921.14921] INFO VolumeManager: [0x1206c80]: Setting new mute state: 0 Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.398 [14921.14921] INFO QobuzConnect: [0x1207650]: Client initialized! Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.398 [14921.14921] INFO SampleApp: Starting Avahi advertising, name: Volumio-hifi, service name: _qobuz-connect._tcp Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.415 [14921.14921] INFO LocalConfigManager: [0x1206560]: Starting Local Configuration server Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.415 [14921.14921] INFO SampleApp: Starting Local configuration server Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.416 [14921.14921] INFO SampleApp: Connected to UNIX socket client 0x11fbf60 Dec 28 14:40:25 volumio-hifi volumio[963]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 14:40:25 volumio-hifi volumio[963]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 14:40:25 volumio-hifi sudo[14934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 14:40:25 volumio-hifi sudo[14934]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 14:40:25 volumio-hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 28 14:40:25 volumio-hifi systemd[1]: shairport-sync.service: Succeeded. Dec 28 14:40:25 volumio-hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 28 14:40:25 volumio-hifi volumio[963]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 28 14:40:25 volumio-hifi qobuz-connect[14921]: 20251228 14:40:25.541 [14921.14921] INFO SampleApp: Playback volume changed: 75 Dec 28 14:40:25 volumio-hifi volumio[963]: info: CoreCommandRouter::volumioGetState Dec 28 14:40:25 volumio-hifi volumio[963]: info: CorePlayQueue::getTrack 0 Dec 28 14:40:25 volumio-hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 28 14:40:25 volumio-hifi sudo[14934]: pam_unix(sudo:session): session closed for user root Dec 28 14:40:25 volumio-hifi volumio[963]: info: Shairport-Sync Started Dec 28 14:40:25 volumio-hifi volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 14:40:25 volumio-hifi volumio[963]: TypeError: Cannot read property 'off' of undefined Dec 28 14:40:25 volumio-hifi volumio[963]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Dec 28 14:40:25 volumio-hifi volumio[963]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 28 14:40:25 volumio-hifi volumio[963]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Dec 28 14:40:25 volumio-hifi volumio[963]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Dec 28 14:40:25 volumio-hifi volumio[963]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Dec 28 14:40:25 volumio-hifi volumio[963]: at Socket.emit (events.js:315:20) Dec 28 14:40:25 volumio-hifi volumio[963]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 28 14:40:25 volumio-hifi volumio[963]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 28 14:40:25 volumio-hifi volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 14:40:26 volumio-hifi sudo[14949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 14:39 Dec 28 14:40:26 volumio-hifi sudo[14949]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"