-- Logs begin at Sat 2025-12-27 10:58:45 CET, end at Sat 2025-12-27 11:12:35 CET. -- Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPlay Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::play index 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::stPlaybackTimer Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::updateTrackBlock Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrackBlock Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 17 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::serviceStop Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 17 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::serviceStop Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::stop Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand stop took 25 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::play index undefined Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::startPlaybackTimer Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSICA/Musica/05 - Yellow.flac Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand stop took 3 milliseconds Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 15 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand clear took 13 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 10 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 9 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 6 milliseconds Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSICA/Musica/05 - Yellow.flac" Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: 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 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: No code Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: 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 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: No code Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: 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 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus stop Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: No code Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 214ms Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 213ms Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 213ms Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: error: updateQueue error: null Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 224ms Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand playlistinfo took 218 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand add "USB/MUSICA/Musica/05 - Yellow.flac" took 219 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 13ms Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 12ms Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 10ms Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand play Dec 27 11:11:00 volumio volumio[672]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 246ms Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces system playlist update Dec 27 11:11:00 volumio volumio[672]: info: Ignoring MPD Status Update Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 55ms Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand play took 49 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 46ms Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 45ms Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 44ms Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Dec 27 11:11:00 volumio volumio[672]: info: FusionDsp - ---- read samplerate from file: 96000 Dec 27 11:11:00 volumio volumio[672]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 11:11:00 volumio volumio[672]: info: FusionDsp - Loudness is ON true Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 41 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 40 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 40 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 36 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 33 milliseconds Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: Dec 27 11:11:00 volumio volumio[672]: ---------------------------- MPD announces state update: player Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::getState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand status Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 17 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 14 milliseconds Dec 27 11:11:00 volumio volumio[672]: info: sendMpdCommand status took 12 milliseconds Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::parseState Dec 27 11:11:00 volumio volumio[672]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus stop Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:00 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:00 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:00 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"205 Kbps","isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:00 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:00 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:00 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:00 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:00 volumio volumio[672]: info: ------------------------------ 180ms Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 365ms Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 358ms Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 356ms Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 354ms Dec 27 11:11:01 volumio volumio[672]: error: FusionDsp - WebSocket error: [object Object] Dec 27 11:11:01 volumio volumio[672]: info: sendMpdCommand playlistinfo took 299 milliseconds Dec 27 11:11:01 volumio volumio[672]: info: sendMpdCommand playlistinfo took 298 milliseconds Dec 27 11:11:01 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:01 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:01 volumio volumio[672]: verbose: ControllerMpd::parseTrackInfo Dec 27 11:11:01 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3059 Kbps","isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:01 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:01 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:01 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3059 Kbps","isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:01 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:01 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:01 volumio volumio[672]: info: ControllerMpd::pushState Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::servicePushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":269,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3059 Kbps","isStreaming":false,"title":"Yellow","artist":"Coldplay","album":"Parachutes","uri":"USB/MUSICA/Musica/05 - Yellow.flac","trackType":"flac"} Dec 27 11:11:01 volumio volumio[672]: verbose: CURRENT POSITION 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::syncState stateService play Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::syncState currentStatus play Dec 27 11:11:01 volumio volumio[672]: info: Received an update from plugin. extracting info from payload Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:01 volumio volumio[672]: info: CoreStateMachine::pushState Dec 27 11:11:01 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:11:01 volumio volumio[672]: info: CoreCommandRouter::volumioPushState Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 501ms Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 505ms Dec 27 11:11:01 volumio volumio[672]: info: ------------------------------ 504ms Dec 27 11:11:13 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 11:11:13 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 27 11:11:24 volumio ntpd[635]: 213.165.71.32 local addr 192.168.1.130 -> Dec 27 11:11:25 volumio ntpd[635]: 93.176.191.241 local addr 192.168.1.130 -> Dec 27 11:11:27 volumio ntpd[635]: 84.77.195.114 local addr 192.168.1.130 -> Dec 27 11:11:59 volumio volumio[672]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Dec 27 11:12:01 volumio volumio[672]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Dec 27 11:12:01 volumio volumio[672]: info: Folder /tmp/plugins removed Dec 27 11:12:03 volumio volumio[672]: info: Check plugin dependencies Dec 27 11:12:03 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 11:12:03 volumio volumio[672]: info: Checking if plugin already exists Dec 27 11:12:03 volumio volumio[672]: info: Rename folder Dec 27 11:12:03 volumio volumio[672]: info: Folder /tmp/downloaded_plugin.zip removed Dec 27 11:12:03 volumio volumio[672]: info: Move to category Dec 27 11:12:04 volumio volumio[672]: info: Checking if install.sh is present Dec 27 11:12:04 volumio volumio[672]: info: Executing install.sh Dec 27 11:12:04 volumio sudo[4563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Dec 27 11:12:04 volumio sudo[4563]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 11:12:04 volumio volumio[672]: info: Installing Go-librespot Dec 27 11:12:04 volumio volumio[672]: info: Checking old vollibrespot installs Dec 27 11:12:04 volumio systemd[1]: Reloading. Dec 27 11:12:05 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 27 11:12:06 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 27 11:12:06 volumio systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 27 11:12:06 volumio volumio[672]: info: Downloading daemon Dec 27 11:12:09 volumio volumio[672]: info: Creating directories Dec 27 11:12:09 volumio volumio[672]: info: Creating data directory Dec 27 11:12:09 volumio volumio[672]: info: Creating Start Script Dec 27 11:12:09 volumio systemd[1]: Reloading. Dec 27 11:12:10 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 27 11:12:11 volumio sudo[4563]: pam_unix(sudo:session): session closed for user root Dec 27 11:12:11 volumio volumio[672]: Plugin install end detected on script Dec 27 11:12:11 volumio volumio[672]: info: Install script completed Dec 27 11:12:11 volumio volumio[672]: info: Adding reference to registry Dec 27 11:12:11 volumio volumio[672]: info: Done installing plugin. Dec 27 11:12:11 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 11:12:11 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 27 11:12:11 volumio volumio[672]: info: Folder /tmp/plugins removed Dec 27 11:12:11 volumio volumio[672]: info: Folder /tmp/downloaded_plugin.zip removed Dec 27 11:12:11 volumio volumio[672]: info: Folder /data/temp removed Dec 27 11:12:13 volumio volumio[672]: info: Enabling plugin spop Dec 27 11:12:13 volumio volumio[672]: info: Loading plugin "spop"... Dec 27 11:12:15 volumio volumio[672]: info: PLUGIN START: spop Dec 27 11:12:15 volumio volumio[672]: info: Creating Spotify config file Dec 27 11:12:15 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 11:12:15 volumio volumio[672]: info: Done. Dec 27 11:12:15 volumio volumio[672]: Upnp client error: Error: This socket has been ended by the other party Dec 27 11:12:15 volumio volumio[672]: info: Spotify config file written Dec 27 11:12:15 volumio volumio[672]: info: No need to fix Spotify hosts Dec 27 11:12:15 volumio sudo[4649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 27 11:12:15 volumio sudo[4649]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 11:12:16 volumio systemd[1]: Started go-librespot Daemon. Dec 27 11:12:16 volumio go-librespot[4653]: go-librespot daemon starting... Dec 27 11:12:16 volumio sudo[4649]: pam_unix(sudo:session): session closed for user root Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=info msg="running go-librespot 0.4.0" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=debug msg="no app state found" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=debug msg="stored credentials not found" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=info msg="generated new device id: a223317b6c2a525560596f0fdeabe85757ebdd4c" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 27 11:12:16 volumio go-librespot[4653]: time="2025-12-27T11:12:16+01:00" level=info msg="zeroconf server listening on port 38275" Dec 27 11:12:18 volumio volumio[672]: info: Enabling plugin spop Dec 27 11:12:18 volumio volumio[672]: info: Loading plugin "spop"... Dec 27 11:12:18 volumio volumio[672]: info: PLUGIN START: spop Dec 27 11:12:18 volumio volumio[672]: info: Creating Spotify config file Dec 27 11:12:18 volumio volumio[672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 11:12:18 volumio volumio[672]: info: Done. Dec 27 11:12:18 volumio volumio[672]: info: Spotify config file written Dec 27 11:12:18 volumio volumio[672]: info: No need to fix Spotify hosts Dec 27 11:12:18 volumio sudo[4666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 27 11:12:18 volumio sudo[4666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 11:12:18 volumio systemd[1]: Stopping go-librespot Daemon... Dec 27 11:12:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 27 11:12:18 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Dec 27 11:12:18 volumio systemd[1]: Stopped go-librespot Daemon. Dec 27 11:12:18 volumio systemd[1]: Started go-librespot Daemon. Dec 27 11:12:18 volumio go-librespot[4668]: go-librespot daemon starting... Dec 27 11:12:18 volumio sudo[4666]: pam_unix(sudo:session): session closed for user root Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=debug msg="app state loaded" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=debug msg="stored credentials not found" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 27 11:12:18 volumio go-librespot[4668]: time="2025-12-27T11:12:18+01:00" level=info msg="zeroconf server listening on port 43515" Dec 27 11:12:19 volumio volumio[672]: info: go-librespot daemon successfully initialized Dec 27 11:12:21 volumio volumio[672]: info: go-librespot daemon successfully initialized Dec 27 11:12:22 volumio volumio[672]: info: Initializing connection to go-librespot Websocket Dec 27 11:12:22 volumio go-librespot[4668]: time="2025-12-27T11:12:22+01:00" level=debug msg="new websocket client" Dec 27 11:12:22 volumio volumio[672]: info: Connection to go-librespot Websocket established Dec 27 11:12:24 volumio volumio[672]: info: Initializing connection to go-librespot Websocket Dec 27 11:12:24 volumio go-librespot[4668]: time="2025-12-27T11:12:24+01:00" level=debug msg="new websocket client" Dec 27 11:12:24 volumio volumio[672]: info: Connection to go-librespot Websocket established Dec 27 11:12:25 volumio volumio[672]: info: Getting Spotify volume Dec 27 11:12:25 volumio volumio[672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 27 11:12:25 volumio volumio[672]: info: CoreCommandRouter::volumioGetState Dec 27 11:12:25 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:12:25 volumio volumio[672]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 27 11:12:25 volumio volumio[672]: SPOTIFY: SPOTIFY VOLUME undefined Dec 27 11:12:25 volumio volumio[672]: SPOTIFY: VOLUMIO VOLUME 100 Dec 27 11:12:25 volumio volumio[672]: info: Aligning Spotify Volume to Volumio Volume Dec 27 11:12:25 volumio volumio[672]: info: CoreCommandRouter::volumioGetState Dec 27 11:12:25 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:12:25 volumio volumio[672]: info: Setting Spotify Volume from Volumio: 100 Dec 27 11:12:25 volumio ntpd[635]: 194.164.164.175 local addr 192.168.1.130 -> Dec 27 11:12:26 volumio volumio[672]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Dec 27 11:12:26 volumio volumio[672]: info: Sending Spotify command with payload to local API: /player/volume Dec 27 11:12:27 volumio volumio[672]: info: Getting Spotify volume Dec 27 11:12:27 volumio volumio[672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 27 11:12:27 volumio volumio[672]: info: CoreCommandRouter::volumioGetState Dec 27 11:12:27 volumio volumio[672]: info: CorePlayQueue::getTrack 16 Dec 27 11:12:27 volumio volumio[672]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 27 11:12:31 volumio volumio[672]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Dec 27 11:12:31 volumio ntpd[635]: 217.160.115.110 local addr 192.168.1.130 -> Dec 27 11:12:31 volumio ntpd[635]: 94.143.139.219 local addr 192.168.1.130 -> Dec 27 11:12:34 volumio volumio[672]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Dec 27 11:12:34 volumio volumio[672]: info: FusionDsp - Stopping FusionDsp service Dec 27 11:12:34 volumio volumio[672]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 11:12:34 volumio volumio[672]: TypeError: Cannot read property 'stop' of null Dec 27 11:12:34 volumio volumio[672]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Dec 27 11:12:34 volumio volumio[672]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 27 11:12:34 volumio volumio[672]: at /volumio/app/pluginmanager.js:956:12 Dec 27 11:12:34 volumio volumio[672]: at ChildProcess.exithandler (child_process.js:299:7) Dec 27 11:12:34 volumio volumio[672]: at ChildProcess.emit (events.js:315:20) Dec 27 11:12:34 volumio volumio[672]: at maybeClose (internal/child_process.js:1048:16) Dec 27 11:12:34 volumio volumio[672]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Dec 27 11:12:34 volumio volumio[672]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 11:12:35 volumio sudo[4749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 11:11 Dec 27 11:12:35 volumio sudo[4749]: 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"