-- Logs begin at Thu 2019-02-14 15:41:58 IST, end at Tue 2026-01-13 09:47:01 IST. -- Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio go-librespot[1343]: time="2026-01-13T09:46:00+05:30" level=trace msg="received accesspoint pong ack" Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::next Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::stop Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::pushState Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrack 113 Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::serviceStop Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrack 113 Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Jan 13 09:46:00 volumio volumio[1047]: info: ControllerMpd::stop Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrack 113 Jan 13 09:46:00 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::getQueue Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getQueue Jan 13 09:46:00 volumio volumio[1047]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 13 09:46:00 volumio volumio[1047]: info: Jan 13 09:46:00 volumio volumio[1047]: ---------------------------- MPD announces state update: player Jan 13 09:46:00 volumio volumio[1047]: info: sendMpdCommand stop took 99 milliseconds Jan 13 09:46:00 volumio volumio[1047]: info: ControllerMpd::getState Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::play index undefined Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::clearAddPlayTracks USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 09:46:00 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Jan 13 09:46:00 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Jan 13 09:46:00 volumio volumio[1047]: info: sendMpdCommand status took 64 milliseconds Jan 13 09:46:00 volumio volumio[1047]: info: sendMpdCommand stop took 58 milliseconds Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::parseState Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 09:46:00 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 09:46:00 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:00 volumio go-librespot[1343]: time="2026-01-13T09:46:00+05:30" level=trace msg="sent dealer ping" Jan 13 09:46:00 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:00 volumio go-librespot[1343]: time="2026-01-13T09:46:00+05:30" level=trace msg="received dealer pong" Jan 13 09:46:00 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:01 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:01 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:01 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:01 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:01 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:02 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:02 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:02 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:02 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:03 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:03 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:03 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:03 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:04 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:05 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:05 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:05 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:05 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:05 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:05 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:06 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:06 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:06 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:07 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:08 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:09 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:09 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:09 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:09 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:09 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:09 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:10 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:10 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:10 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:10 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:10 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:10 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:11 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:12 volumio volumio[1047]: info: Jan 13 09:46:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Jan 13 09:46:12 volumio volumio[1047]: info: Ignoring MPD Status Update Jan 13 09:46:12 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 12147 milliseconds Jan 13 09:46:12 volumio volumio[1047]: info: sendMpdCommand clear took 12138 milliseconds Jan 13 09:46:12 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Jan 13 09:46:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand add "USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac" Jan 13 09:46:12 volumio volumio[1047]: info: ControllerMpd::pushState Jan 13 09:46:12 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Jan 13 09:46:12 volumio volumio[1047]: info: CoreStateMachine::pushState Jan 13 09:46:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:12 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 09:46:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jan 13 09:46:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:12 volumio volumio[1047]: 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":"04 Kuchi Kuchi.flac","artist":null,"album":null,"uri":"USB/9E127C75127C53ED/VolumioMusic/04 Kuchi Kuchi.flac","trackType":"flac"} Jan 13 09:46:12 volumio volumio[1047]: verbose: CURRENT POSITION 97 Jan 13 09:46:12 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Jan 13 09:46:12 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Jan 13 09:46:12 volumio volumio[1047]: info: CoreStateMachine::pushState Jan 13 09:46:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jan 13 09:46:12 volumio volumio[1047]: info: No code Jan 13 09:46:12 volumio volumio[1047]: info: CoreStateMachine::pushState Jan 13 09:46:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jan 13 09:46:12 volumio volumio[1047]: info: ------------------------------ 12305ms Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:12 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:13 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:14 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:15 volumio volumio[1047]: error: updateQueue error: null Jan 13 09:46:15 volumio volumio[1047]: info: Jan 13 09:46:15 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Jan 13 09:46:15 volumio volumio[1047]: info: Ignoring MPD Status Update Jan 13 09:46:15 volumio volumio[1047]: info: ------------------------------ 3221ms Jan 13 09:46:15 volumio volumio[1047]: info: sendMpdCommand add "USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac" took 3213 milliseconds Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Jan 13 09:46:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::play index undefined Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 09:46:15 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::next Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::stop Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 09:46:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Jan 13 09:46:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 97 Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::clearAddPlayTracks USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::play index undefined Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 09:46:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Jan 13 09:46:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::clearAddPlayTracks USB/9E127C75127C53ED/VolumioMusic/03 - Roja Roja.flac Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 09:46:15 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Jan 13 09:46:15 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Jan 13 09:46:15 volumio volumio[1047]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 13 09:46:15 volumio volumio[1047]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 13 09:46:15 volumio volumio[1047]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Jan 13 09:46:15 volumio volumio[1047]: info: Jan 13 09:46:15 volumio volumio[1047]: ---------------------------- MPD announces state update: player Jan 13 09:46:15 volumio volumio[1047]: info: Jan 13 09:46:15 volumio volumio[1047]: ---------------------------- MPD announces state update: player Jan 13 09:46:15 volumio volumio[1047]: info: ------------------------------ 90ms Jan 13 09:46:15 volumio volumio[1047]: info: sendMpdCommand play took 87 milliseconds Jan 13 09:46:15 volumio volumio[1047]: info: ControllerMpd::getState Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Jan 13 09:46:15 volumio volumio[1047]: info: sendMpdCommand stop took 78 milliseconds Jan 13 09:46:15 volumio volumio[1047]: info: ControllerMpd::getState Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Jan 13 09:46:15 volumio volumio[1047]: info: sendMpdCommand stop took 77 milliseconds Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 09:46:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - ---- read samplerate from file: 384000 Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - File size found in array! Jan 13 09:46:15 volumio volumio[1047]: info: FusionDsp - Nulleq applied Jan 13 09:46:15 volumio volumio[1047]: info: Jan 13 09:46:15 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Jan 13 09:46:15 volumio volumio[1047]: info: Ignoring MPD Status Update Jan 13 09:46:15 volumio volumio[1047]: info: Jan 13 09:46:15 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Jan 13 09:46:15 volumio volumio[1047]: info: Ignoring MPD Status Update Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand status took 211 milliseconds Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand clear took 210 milliseconds Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand clear took 210 milliseconds Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::parseState Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::parseState Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand add "USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac" Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand add "USB/9E127C75127C53ED/VolumioMusic/03 - Roja Roja.flac" Jan 13 09:46:16 volumio volumio[1047]: error: updateQueue error: null Jan 13 09:46:16 volumio volumio[1047]: error: updateQueue error: null Jan 13 09:46:16 volumio volumio[1047]: info: Jan 13 09:46:16 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Jan 13 09:46:16 volumio volumio[1047]: info: Ignoring MPD Status Update Jan 13 09:46:16 volumio volumio[1047]: info: ------------------------------ 119ms Jan 13 09:46:16 volumio volumio[1047]: info: ------------------------------ 119ms Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 116 milliseconds Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 116 milliseconds Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand add "USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac" took 116 milliseconds Jan 13 09:46:16 volumio volumio[1047]: info: sendMpdCommand add "USB/9E127C75127C53ED/VolumioMusic/03 - Roja Roja.flac" took 116 milliseconds Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Jan 13 09:46:16 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Jan 13 09:46:16 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 13 09:46:16 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 13 09:46:16 volumio volumio[1047]: info: ------------------------------ 348ms Jan 13 09:46:16 volumio volumio[1047]: info: ------------------------------ 349ms Jan 13 09:46:17 volumio volumio[1047]: error: FusionDsp - WebSocket error: [object Object] Jan 13 09:46:17 volumio volumio[1047]: info: Jan 13 09:46:17 volumio volumio[1047]: ---------------------------- MPD announces state update: player Jan 13 09:46:17 volumio volumio[1047]: info: ------------------------------ 1417ms Jan 13 09:46:17 volumio volumio[1047]: info: sendMpdCommand play took 1411 milliseconds Jan 13 09:46:17 volumio volumio[1047]: info: ControllerMpd::getState Jan 13 09:46:17 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Jan 13 09:46:17 volumio volumio[1047]: info: sendMpdCommand play took 1416 milliseconds Jan 13 09:46:17 volumio volumio[1047]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Jan 13 09:46:17 volumio volumio[1047]: info: FusionDsp - ---- read samplerate from file: 384000 Jan 13 09:46:17 volumio volumio[1047]: info: FusionDsp - File size found in array! Jan 13 09:46:17 volumio volumio[1047]: info: FusionDsp - Nulleq applied Jan 13 09:46:17 volumio volumio[1047]: info: sendMpdCommand status took 124 milliseconds Jan 13 09:46:17 volumio volumio[1047]: verbose: ControllerMpd::parseState Jan 13 09:46:17 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 09:46:17 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 87 milliseconds Jan 13 09:46:17 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Jan 13 09:46:17 volumio volumio[1047]: info: ControllerMpd::pushState Jan 13 09:46:17 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Jan 13 09:46:17 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:17 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":472,"duration":294,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1326 Kbps","isStreaming":false,"title":"04 - Aathangara Marame.flac","artist":null,"album":null,"uri":"USB/9E127C75127C53ED/VolumioMusic/04 - Aathangara Marame.flac","trackType":"flac"} Jan 13 09:46:17 volumio volumio[1047]: verbose: CURRENT POSITION 75 Jan 13 09:46:17 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Jan 13 09:46:17 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Jan 13 09:46:17 volumio volumio[1047]: info: ------------------------------ 222ms Jan 13 09:46:17 volumio volumio[1047]: error: FusionDsp - WebSocket error: [object Object] Jan 13 09:46:17 volumio volumio[1047]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 09:46:17 volumio volumio[1047]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 13 09:46:18 volumio sudo[4713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 09:46:18 volumio sudo[4713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:18 volumio sudo[4713]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:18 volumio sudo[4716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 09:46:18 volumio sudo[4716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:18 volumio sudo[4716]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:18 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.100 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 09:46:18 volumio volumio[1047]: info: Received Get System Info Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 09:46:18 volumio volumio[1047]: info: Discovery: Getting this device information Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 09:46:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:19 volumio volumio[1047]: info: Listing playlists Jan 13 09:46:19 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 13 09:46:19 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 09:46:19 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 09:46:19 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 13 09:46:19 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:19 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:19 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:19 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:19 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:19 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 09:46:20 volumio volumio[1047]: info: Received Get System Info Jan 13 09:46:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 09:46:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 09:46:20 volumio volumio[1047]: info: Discovery: Getting this device information Jan 13 09:46:20 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:20 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 09:46:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 13 09:46:22 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 09:46:22 volumio volumio[1047]: info: Received Get System Info Jan 13 09:46:22 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 09:46:22 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 09:46:22 volumio volumio[1047]: info: Discovery: Getting this device information Jan 13 09:46:22 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:22 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:22 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 09:46:24 volumio volumio[1047]: info: Starting Uninstall of plugin audio_interface - mpdoutput Jan 13 09:46:24 volumio volumio[1047]: info: Uninstalling plugin mpdoutput Jan 13 09:46:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jan 13 09:46:24 volumio volumio[1047]: info: Disabling plugin mpdoutput Jan 13 09:46:24 volumio sudo[4902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 09:46:24 volumio sudo[4902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:24 volumio volumio[1047]: info: Checking if uninstall.sh is present Jan 13 09:46:24 volumio volumio[1047]: info: Executing uninstall.sh Jan 13 09:46:24 volumio systemd[1]: Stopping Music Player Daemon... Jan 13 09:46:24 volumio systemd[1]: mpd.service: Succeeded. Jan 13 09:46:24 volumio systemd[1]: Stopped Music Player Daemon. Jan 13 09:46:25 volumio systemd[1]: Starting Music Player Daemon... Jan 13 09:46:25 volumio sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/uninstall.sh Jan 13 09:46:25 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:25 volumio sudo[4906]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:25 volumio volumio[1047]: info: Uninstall script completed Jan 13 09:46:25 volumio volumio[1047]: info: Removing plugin mpdoutput from configuration Jan 13 09:46:25 volumio sudo[4907]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 09:46:25 volumio sudo[4907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:25 volumio sudo[4907]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:25 volumio volumio[1047]: info: Successfully removed mpdoutput configuration files Jan 13 09:46:25 volumio volumio[1047]: info: Plugin folders cleanup Jan 13 09:46:25 volumio volumio[1047]: info: Scanning into folder /volumio/app/plugins/ Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category audio_interface Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category miscellanea Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category music_service Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category plugins.json Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category system_controller Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category user_interface Jan 13 09:46:25 volumio volumio[1047]: info: Scanning into folder /data/plugins/ Jan 13 09:46:25 volumio volumio[1047]: info: Scanning category audio_interface Jan 13 09:46:25 volumio volumio[1047]: info: Cleaning folder for mpdoutput Jan 13 09:46:26 volumio volumio[1047]: info: Scanning category music_service Jan 13 09:46:26 volumio volumio[1047]: info: Scanning category system_controller Jan 13 09:46:26 volumio volumio[1047]: info: Scanning category system_hardware Jan 13 09:46:26 volumio volumio[1047]: info: Plugin folders cleanup completed Jan 13 09:46:26 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 09:46:26 volumio volumio[1047]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Squeezelite MC","name":"squeezelite_mc","category":"music_service","version":"1.0.4","icon":"fa-play-circle-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"2.0.0","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"IR Activity LED","name":"iractivityled","category":"system_hardware","version":"1.0.0","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"MPD OLED","name":"mpd_oled","category":"system_hardware","version":"1.1.3","icon":"fa-television","isManuallyInstalled":false,"enabled":true,"active":true}] Jan 13 09:46:26 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 09:46:26 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:26 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:26 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:26 volumio mpd[4918]: Jan 13 09:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 09:46:26 volumio systemd[1]: Started Music Player Daemon. Jan 13 09:46:26 volumio sudo[4902]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:26 volumio volumio[1047]: error: updateQueue error: null Jan 13 09:46:28 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 13 09:46:29 volumio sudo[4980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 13 09:46:29 volumio sudo[4980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:29 volumio systemd[1]: Stopping MPD Monitor Service... Jan 13 09:46:29 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 13 09:46:29 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 13 09:46:29 volumio systemd[1]: Stopped MPD Monitor Service. Jan 13 09:46:29 volumio systemd[1]: Started MPD Monitor Service. Jan 13 09:46:30 volumio mpd_monitor.sh[4982]: MPD Monitor Service: Starting MPD Monitor Service Jan 13 09:46:30 volumio sudo[4980]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:30 volumio volumio[1047]: info: Successfully started MPD Monitor Jan 13 09:46:30 volumio go-librespot[1343]: time="2026-01-13T09:46:30+05:30" level=trace msg="sent dealer ping" Jan 13 09:46:30 volumio go-librespot[1343]: time="2026-01-13T09:46:30+05:30" level=trace msg="received dealer pong" Jan 13 09:46:33 volumio volumio[1047]: info: Starting Uninstall of plugin music_service - squeezelite_mc Jan 13 09:46:33 volumio volumio[1047]: info: Uninstalling plugin squeezelite_mc Jan 13 09:46:33 volumio volumio[1047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jan 13 09:46:33 volumio volumio[1047]: info: [squeezelite_mc] Proxy server stopped Jan 13 09:46:33 volumio sudo[4987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jan 13 09:46:33 volumio sudo[4987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:33 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Jan 13 09:46:33 volumio systemd[1]: squeezelite.service: Succeeded. Jan 13 09:46:33 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jan 13 09:46:33 volumio sudo[4987]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:33 volumio kernel: hwmon hwmon1: Voltage normalised Jan 13 09:46:34 volumio volumio[1047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jan 13 09:46:34 volumio sudo[4991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jan 13 09:46:34 volumio sudo[4991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:34 volumio sudo[4991]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:34 volumio volumio[1047]: info: Disabling plugin squeezelite_mc Jan 13 09:46:34 volumio volumio[1047]: info: Checking if uninstall.sh is present Jan 13 09:46:34 volumio volumio[1047]: info: Executing uninstall.sh Jan 13 09:46:34 volumio sudo[4995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/squeezelite_mc/uninstall.sh Jan 13 09:46:34 volumio sudo[4995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:34 volumio systemd[1]: Reloading. Jan 13 09:46:35 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. Jan 13 09:46:35 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 13 09:46:36 volumio sudo[4995]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:36 volumio volumio[1047]: info: Uninstall script completed Jan 13 09:46:36 volumio volumio[1047]: info: Removing plugin squeezelite_mc from configuration Jan 13 09:46:36 volumio volumio[1047]: info: Successfully removed squeezelite_mc configuration files Jan 13 09:46:36 volumio volumio[1047]: info: Plugin folders cleanup Jan 13 09:46:36 volumio volumio[1047]: info: Scanning into folder /volumio/app/plugins/ Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category audio_interface Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category miscellanea Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category music_service Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category plugins.json Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category system_controller Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category user_interface Jan 13 09:46:36 volumio volumio[1047]: info: Scanning into folder /data/plugins/ Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category audio_interface Jan 13 09:46:36 volumio volumio[1047]: info: Scanning category music_service Jan 13 09:46:36 volumio volumio[1047]: info: Cleaning folder for squeezelite_mc Jan 13 09:46:37 volumio volumio[1047]: info: Scanning category system_controller Jan 13 09:46:37 volumio volumio[1047]: info: Scanning category system_hardware Jan 13 09:46:37 volumio volumio[1047]: info: Plugin folders cleanup completed Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 09:46:37 volumio volumio[1047]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"2.0.0","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"IR Activity LED","name":"iractivityled","category":"system_hardware","version":"1.0.0","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"MPD OLED","name":"mpd_oled","category":"system_hardware","version":"1.1.3","icon":"fa-television","isManuallyInstalled":false,"enabled":true,"active":true}] Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 13 09:46:37 volumio volumio[1047]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 13 09:46:37 volumio volumio[1047]: info: Received Get System Version Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 09:46:37 volumio volumio[1047]: info: Received Get System Info Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 09:46:37 volumio volumio[1047]: info: Discovery: Getting this device information Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jan 13 09:46:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 75 Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 09:46:37 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:37 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:38 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:43 volumio volumio[1047]: info: AutoStart - onStop Jan 13 09:46:43 volumio volumio[1047]: info: Disabling plugin autostart Jan 13 09:46:43 volumio volumio[1047]: info: Done. Jan 13 09:46:43 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:43 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:43 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:44 volumio volumio[1047]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube2 Jan 13 09:46:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 09:46:44 volumio volumio[1047]: Cannot find translation for source Spotify Jan 13 09:46:44 volumio volumio[1047]: info: Disabling plugin youtube2 Jan 13 09:46:44 volumio volumio[1047]: info: Done. Jan 13 09:46:44 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 09:46:44 volumio volumio[1047]: info: FusionDsp - kef Jan 13 09:46:44 volumio volumio[1047]: info: FusionDsp - kef.json,test.json Jan 13 09:46:49 volumio volumio[1047]: info: Starting Uninstall of plugin system_hardware - audiophonics_evo_sabre Jan 13 09:46:49 volumio volumio[1047]: info: Uninstalling plugin audiophonics_evo_sabre Jan 13 09:46:49 volumio sudo[5049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop evo_oled2.service Jan 13 09:46:49 volumio sudo[5049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:49 volumio systemd[1]: Stopping OLED Display Service for EVO SABRE... Jan 13 09:46:49 volumio systemd[1]: evo_oled2.service: Succeeded. Jan 13 09:46:49 volumio systemd[1]: Stopped OLED Display Service for EVO SABRE. Jan 13 09:46:49 volumio sudo[5049]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:49 volumio volumio[1047]: info: EVO SABRE : systemd cmd stop evo_oled2.service : success Jan 13 09:46:49 volumio sudo[5052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Jan 13 09:46:49 volumio sudo[5052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:49 volumio sudo[5052]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:49 volumio volumio[1047]: error: EVO SABRE : systemd failed cmd stop lircd.service : Error: Command failed: /usr/bin/sudo /bin/systemctl stop lircd.service Jan 13 09:46:49 volumio volumio[1047]: Failed to stop lircd.service: Unit lircd.service not loaded. Jan 13 09:46:50 volumio kernel: hwmon hwmon1: Voltage normalised Jan 13 09:46:53 volumio sudo[5059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop evo_oled2.service Jan 13 09:46:53 volumio sudo[5059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:53 volumio sudo[5059]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:53 volumio volumio[1047]: info: EVO SABRE : systemd cmd stop evo_oled2.service : success Jan 13 09:46:53 volumio sudo[5062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Jan 13 09:46:53 volumio sudo[5062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:53 volumio sudo[5062]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:53 volumio volumio[1047]: error: EVO SABRE : systemd failed cmd stop lircd.service : Error: Command failed: /usr/bin/sudo /bin/systemctl stop lircd.service Jan 13 09:46:53 volumio volumio[1047]: Failed to stop lircd.service: Unit lircd.service not loaded. Jan 13 09:46:53 volumio volumio[1047]: info: Error: undefined Jan 13 09:46:55 volumio volumio[1047]: info: Starting Uninstall of plugin system_hardware - audiophonics_evo_sabre Jan 13 09:46:55 volumio volumio[1047]: info: Uninstalling plugin audiophonics_evo_sabre Jan 13 09:46:55 volumio sudo[5065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop evo_oled2.service Jan 13 09:46:55 volumio sudo[5065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:55 volumio sudo[5065]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:55 volumio volumio[1047]: info: EVO SABRE : systemd cmd stop evo_oled2.service : success Jan 13 09:46:55 volumio sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Jan 13 09:46:55 volumio sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 09:46:55 volumio sudo[5068]: pam_unix(sudo:session): session closed for user root Jan 13 09:46:55 volumio volumio[1047]: error: EVO SABRE : systemd failed cmd stop lircd.service : Error: Command failed: /usr/bin/sudo /bin/systemctl stop lircd.service Jan 13 09:46:55 volumio volumio[1047]: Failed to stop lircd.service: Unit lircd.service not loaded. Jan 13 09:47:00 volumio go-librespot[1343]: time="2026-01-13T09:47:00+05:30" level=trace msg="sent dealer ping" Jan 13 09:47:00 volumio go-librespot[1343]: time="2026-01-13T09:47:00+05:30" level=trace msg="received dealer pong" Jan 13 09:47:00 volumio volumio[1047]: info: Starting Uninstall of plugin system_hardware - iractivityled Jan 13 09:47:00 volumio volumio[1047]: info: Uninstalling plugin iractivityled Jan 13 09:47:00 volumio volumio[1047]: info: [IrActivityLed] releasing built-in LED Jan 13 09:47:00 volumio volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 09:47:00 volumio volumio[1047]: TypeError: Cannot read property 'toString' of undefined Jan 13 09:47:00 volumio volumio[1047]: at IrActivityLedController.releaseLed (/data/plugins/system_hardware/iractivityled/index.js:232:61) Jan 13 09:47:00 volumio volumio[1047]: at IrActivityLedController.onStop (/data/plugins/system_hardware/iractivityled/index.js:68:17) Jan 13 09:47:00 volumio volumio[1047]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 13 09:47:00 volumio volumio[1047]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jan 13 09:47:00 volumio volumio[1047]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jan 13 09:47:00 volumio volumio[1047]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1271:45) Jan 13 09:47:00 volumio volumio[1047]: at Socket.emit (events.js:315:20) Jan 13 09:47:00 volumio volumio[1047]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 13 09:47:00 volumio volumio[1047]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 13 09:47:00 volumio volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 09:47:01 volumio sudo[5093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 09:46 Jan 13 09:47:01 volumio sudo[5093]: 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"