-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-12-10 22:13:57 CET. -- Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioNext Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::next Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::stop Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::updateTrackBlock Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrackBlock Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 1 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::serviceStop Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 1 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::serviceStop Dec 10 22:13:40 minidsp-shd volumio[898]: info: ControllerMpd::stop Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 22:13:40 minidsp-shd volumio[898]: info: Dec 10 22:13:40 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand stop took 56 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::play index undefined Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::startPlaybackTimer Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::clearAddPlayTracks NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::updateTrackBlock Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrackBlock Dec 10 22:13:40 minidsp-shd volumio[898]: info: Dec 10 22:13:40 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:40 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:40 minidsp-shd volumio[898]: info: Dec 10 22:13:40 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:40 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand status took 42 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand stop took 36 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand status took 25 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand status took 21 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:40 minidsp-shd volumio[898]: info: Dec 10 22:13:40 minidsp-shd volumio[898]: ---------------------------- MPD announces system playlist update Dec 10 22:13:40 minidsp-shd volumio[898]: info: Ignoring MPD Status Update Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand clear took 13 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand add "NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac" Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:40 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Out Of The Blue","artist":"Sinegoma","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/02 Out Of The Blue.flac","trackType":"flac"} Dec 10 22:13:40 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService stop Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus stop Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:40 minidsp-shd volumio[898]: info: No code Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:40 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:40 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:40 minidsp-shd volumio[898]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 10 22:13:40 minidsp-shd volumio[898]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 214ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 198ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 190ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces system playlist update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Ignoring MPD Status Update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces system playlist update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Ignoring MPD Status Update Dec 10 22:13:41 minidsp-shd volumio[898]: error: updateQueue error: null Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces system playlist update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Ignoring MPD Status Update Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 168ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand add "NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac" took 160 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 17ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 14ms Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand play Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces system playlist update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Ignoring MPD Status Update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces system playlist update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Ignoring MPD Status Update Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 75ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand play took 67 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 66ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 60ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand status took 61 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand status took 53 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand status took 38 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:41 minidsp-shd volumio[898]: info: Dec 10 22:13:41 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand status took 49 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 40 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand status took 29 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand status took 24 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService play Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus stop Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService play Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:13:41 minidsp-shd volumio[898]: info: Received an update from plugin. extracting info from payload Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService play Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:13:41 minidsp-shd volumio[898]: info: Received an update from plugin. extracting info from payload Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 219ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 364ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 345ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 277 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 271 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 271 milliseconds Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"628 Kbps","isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService play Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:13:41 minidsp-shd volumio[898]: info: Received an update from plugin. extracting info from payload Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"628 Kbps","isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService play Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:13:41 minidsp-shd volumio[898]: info: Received an update from plugin. extracting info from payload Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"628 Kbps","isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:41 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService play Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:13:41 minidsp-shd volumio[898]: info: Received an update from plugin. extracting info from payload Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:41 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:41 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 648ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 624ms Dec 10 22:13:41 minidsp-shd volumio[898]: info: ------------------------------ 621ms Dec 10 22:13:54 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 10 22:13:54 minidsp-shd volumio[898]: info: Pausing Remote Device: http://192.168.0.31 Dec 10 22:13:54 minidsp-shd volumio[898]: info: Dec 10 22:13:54 minidsp-shd volumio[898]: ---------------------------- Client requests Volumio pause Dec 10 22:13:54 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPause Dec 10 22:13:54 minidsp-shd volumio[898]: info: CoreStateMachine::pause Dec 10 22:13:54 minidsp-shd volumio[898]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:13:54 minidsp-shd volumio[898]: info: CoreStateMachine::servicePause Dec 10 22:13:54 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:54 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePause Dec 10 22:13:54 minidsp-shd volumio[898]: info: ControllerMpd::pause Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand pause Dec 10 22:13:54 minidsp-shd volumio[898]: info: Dec 10 22:13:54 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:54 minidsp-shd volumio[898]: info: sendMpdCommand pause took 6 milliseconds Dec 10 22:13:54 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:54 minidsp-shd volumio[898]: info: Dec 10 22:13:54 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:54 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:54 minidsp-shd volumio[898]: info: Dec 10 22:13:54 minidsp-shd volumio[898]: ---------------------------- MPD announces state update: player Dec 10 22:13:54 minidsp-shd volumio[898]: info: ControllerMpd::getState Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:13:54 minidsp-shd volumio[898]: info: sendMpdCommand status took 17 milliseconds Dec 10 22:13:54 minidsp-shd volumio[898]: info: sendMpdCommand status took 8 milliseconds Dec 10 22:13:54 minidsp-shd volumio[898]: info: sendMpdCommand status took 4 milliseconds Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::parseState Dec 10 22:13:54 minidsp-shd volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:13:55 minidsp-shd volumio[898]: info: Done pausing: Dec 10 22:13:55 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 10 22:13:55 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 10 22:13:55 minidsp-shd volumio[898]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:13:55 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14849,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"359 Kbps","isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService pause Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus pause Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:55 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:13:55 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14849,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"359 Kbps","isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService pause Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus pause Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:55 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:13:55 minidsp-shd volumio[898]: info: ControllerMpd::pushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::servicePushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14849,"duration":501,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"359 Kbps","isStreaming":false,"title":"Lower Ground","artist":"Adama","album":"Eastern Winds","uri":"NAS/Server-Flac1/Adama/Eastern Winds/03 Lower Ground.flac","trackType":"flac"} Dec 10 22:13:55 minidsp-shd volumio[898]: verbose: CURRENT POSITION 2 Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::syncState stateService pause Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::syncState currentStatus pause Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::pushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: CorePlayQueue::getTrack 2 Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreCommandRouter::volumioPushState Dec 10 22:13:55 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:13:55 minidsp-shd volumio[898]: info: MRS: Pushing multiroomSync output Dec 10 22:13:55 minidsp-shd volumio[898]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:13:55 minidsp-shd volumio[898]: info: ------------------------------ 197ms Dec 10 22:13:55 minidsp-shd volumio[898]: info: ------------------------------ 188ms Dec 10 22:13:55 minidsp-shd volumio[898]: info: ------------------------------ 185ms Dec 10 22:13:56 minidsp-shd volumio[898]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 10 22:13:56 minidsp-shd volumio[898]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 10 22:13:56 minidsp-shd volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 22:13:56 minidsp-shd volumio[898]: TypeError: Cannot read property 'then' of undefined Dec 10 22:13:56 minidsp-shd volumio[898]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 10 22:13:56 minidsp-shd volumio[898]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 10 22:13:56 minidsp-shd volumio[898]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 10 22:13:56 minidsp-shd volumio[898]: at Socket.emit (events.js:400:28) Dec 10 22:13:56 minidsp-shd volumio[898]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 10 22:13:56 minidsp-shd volumio[898]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 10 22:13:56 minidsp-shd volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 22:13:57 minidsp-shd sudo[6601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-10 22:12 Dec 10 22:13:57 minidsp-shd sudo[6601]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:27:40 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="861d146b0ca5e28d2e157bf87e6b15ea"