-- Logs begin at Tue 2025-11-04 22:16:09 EET, end at Tue 2025-11-04 22:35:08 EET. -- Nov 04 22:34:05 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:05 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 Nov 04 22:34:07 volumio volumio[1074]: info: Executing endpoint metavolumio Nov 04 22:34:07 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 04 22:34:07 volumio volumio[1074]: info: Executing endpoint metavolumio Nov 04 22:34:07 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 04 22:34:07 volumio volumio[1074]: info: Executing endpoint metavolumio Nov 04 22:34:07 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 04 22:34:09 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 Nov 04 22:34:09 volumio volumio[1074]: info: Listing playlists Nov 04 22:34:09 volumio volumio[1074]: info: Listing playlists Nov 04 22:34:10 volumio volumio[1074]: info: Preload queue cleared Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::ClearQueue Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::clearPlayQueue Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::saveQueue Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::addQueueItems Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::addQueueItems Nov 04 22:34:10 volumio volumio[1074]: info: Preload queue cleared Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/01. GOOD HANDS TONIGHT.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/01. GOOD HANDS TONIGHT.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/02. IF YOU HAD A CHANCE.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/02. IF YOU HAD A CHANCE.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/03. SOME OF MY BEST FRIENDS ARE THE BLUES.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/03. SOME OF MY BEST FRIENDS ARE THE BLUES.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/04. ANYTHING GOES.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/04. ANYTHING GOES.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/05. THIS IS ALWAYS.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/05. THIS IS ALWAYS.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/06. EVERYTHING MUST CHANGE.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/06. EVERYTHING MUST CHANGE.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/07. NOTHING COMPARES TO YOU.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/07. NOTHING COMPARES TO YOU.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::saveQueue Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::play index 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::addQueueItems Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::addQueueItems Nov 04 22:34:10 volumio volumio[1074]: info: Preload queue cleared Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/09. SACK FULL OF DREAMS.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/09. SACK FULL OF DREAMS.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/10. WILD IS THE WIND.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/10. WILD IS THE WIND.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/11. MIDNIGHT PRAYER.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/11. MIDNIGHT PRAYER.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Adding Item to queue: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/12. DR. FEELGOOD.dsf Nov 04 22:34:10 volumio volumio[1074]: info: Using cached record of: music-library/USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/12. DR. FEELGOOD.dsf Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::saveQueue Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::clearAddPlayTracks USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand stop took 4 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:34:10 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 0 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf" Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:34:10 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 2ms Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand add "USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf" took 1 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 1 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 1 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 1 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 0 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: 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} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: No code Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: 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} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: No code Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: 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} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: No code Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: 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} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: No code Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 13ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 24ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 24ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 25ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 25ms Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:34:10 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:34:10 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:34:10 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:34:10 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 25ms Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand play took 24 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 1ms Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 2ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 1ms Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: Nov 04 22:34:10 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8245]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8248]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8245]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8248]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8251]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8251]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8254]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8254]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8257]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8260]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8257]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8260]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8263]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8266]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8263]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8269]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8269]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8266]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8272]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8272]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8275]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8275]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8278]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8278]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8281]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8281]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8284]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8284]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8287]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8290]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8287]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8293]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8290]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8293]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8296]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8299]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8296]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8299]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8302]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8302]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8305]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8308]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8305]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8308]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 317 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 317 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 316 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 316 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 316 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand status took 316 milliseconds Nov 04 22:34:10 volumio sudo[8314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:34:10 volumio sudo[8314]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8311]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8314]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand clearerror took 20 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand clearerror took 20 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand clearerror took 20 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand clearerror took 20 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand clearerror took 20 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 04 22:34:10 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService pause Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService pause Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService pause Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService pause Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService pause Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:34:10 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState stateService pause Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:34:10 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:34:10 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:34:10 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:10 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 348ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 348ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 347ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 347ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 347ms Nov 04 22:34:10 volumio volumio[1074]: info: ------------------------------ 347ms Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [ASDebug] CurState: stop PrevState: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: [GPIO_Control] Status changed: stop Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8317]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8317]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8326]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8323]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8326]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8329]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8332]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8329]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppySpectrum ---peppyspectrum status stop Nov 04 22:34:10 volumio sudo[8335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8335]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8332]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:10 volumio sudo[8338]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8335]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8338]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8341]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8344]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio sudo[8341]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 04 22:34:10 volumio sudo[8344]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio sudo[8347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8347]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio sudo[8350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:10 volumio sudo[8350]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio volumio[1074]: info: peppyspectrum Daemon Stop Nov 04 22:34:10 volumio sudo[8347]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:10 volumio sudo[8350]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:10 volumio volumio[1074]: info: peppymeterbasic Daemon Stop Nov 04 22:34:18 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:18 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:19 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:19 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:19 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 22:34:19 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 22:34:20 volumio volumio[1074]: info: Executing endpoint metavolumio Nov 04 22:34:20 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 04 22:34:20 volumio volumio[1074]: info: Executing endpoint metavolumio Nov 04 22:34:20 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 04 22:34:20 volumio volumio[1074]: info: Executing endpoint metavolumio Nov 04 22:34:20 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 04 22:34:29 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:29 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:29 volumio volumio[1074]: info: Listing playlists Nov 04 22:34:29 volumio volumio[1074]: info: Listing playlists Nov 04 22:34:30 volumio volumio[1074]: info: Stopping peppyspectrum service Nov 04 22:34:30 volumio volumio[1074]: info: CoreStateMachine::stop Nov 04 22:34:30 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:34:30 volumio volumio[1074]: info: Disabling plugin peppyspectrum Nov 04 22:34:30 volumio volumio[1074]: info: Preparing to generate the ALSA configuration file Nov 04 22:34:30 volumio sudo[8445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 04 22:34:30 volumio sudo[8445]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio volumio[1074]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 04 22:34:30 volumio volumio[1074]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 04 22:34:30 volumio volumio[1074]: info: Reading ALSA contributions from plugins. Nov 04 22:34:30 volumio volumio[1074]: info: Asound.conf file written Nov 04 22:34:30 volumio sudo[8445]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio sudo[8448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 04 22:34:30 volumio sudo[8448]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio sudo[8448]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio volumio[1074]: xcb_connection_has_error() returned true Nov 04 22:34:30 volumio volumio[1074]: info: Output device has changed, restarting MPD Nov 04 22:34:30 volumio sudo[8453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 04 22:34:30 volumio sudo[8453]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio sudo[8453]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 22:34:30 volumio sudo[8456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 22:34:30 volumio sudo[8456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 04 22:34:30 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:30 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:30 volumio systemd[1]: Stopping Music Player Daemon... Nov 04 22:34:30 volumio volumio[1074]: xcb_connection_has_error() returned true Nov 04 22:34:30 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 04 22:34:30 volumio volumio[1074]: xcb_connection_has_error() returned true Nov 04 22:34:30 volumio volumio[1074]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:30 volumio systemd[1]: mpd.service: Succeeded. Nov 04 22:34:30 volumio systemd[1]: Stopped Music Player Daemon. Nov 04 22:34:30 volumio volumio[1074]: info: QobuzConnect: setDeactiveState invoked Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:30 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:30 volumio vtcs[7706]: [2025-11-04 22:34:30.412] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Nov 04 22:34:30 volumio vtcs[7706]: [2025-11-04 22:34:30.412] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Nov 04 22:34:30 volumio systemd[1]: Starting Music Player Daemon... Nov 04 22:34:30 volumio volumio[1074]: info: Done. Nov 04 22:34:30 volumio sudo[8465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 04 22:34:30 volumio sudo[8465]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 04 22:34:30 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 04 22:34:30 volumio systemd[1]: vtcs.service: Succeeded. Nov 04 22:34:30 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 04 22:34:30 volumio sudo[8461]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 22:34:30 volumio sudo[8461]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio sudo[8468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 04 22:34:30 volumio sudo[8468]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio sudo[8465]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio sudo[8461]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio sudo[8475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 04 22:34:30 volumio sudo[8468]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio sudo[8475]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:30 volumio sudo[8475]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio volumio[1074]: info: MPD Permissions set Nov 04 22:34:30 volumio sudo[8480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 04 22:34:30 volumio sudo[8480]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:30 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 04 22:34:30 volumio qobuz-connect[7622]: 20251104 22:34:30.490 [7622.7622] INFO SampleApp: Stopping Local configuration server Nov 04 22:34:30 volumio qobuz-connect[7622]: 20251104 22:34:30.500 [7622.7622] INFO SampleApp: shat down connection on UNIX socket Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:30 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 04 22:34:30 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:30 volumio volumio[1074]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 04 22:34:30 volumio volumio[1074]: info: QobuzConnect: setDeactiveState invoked Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:30 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:30 volumio volumio[1074]: info: Starting Shairport Sync Nov 04 22:34:30 volumio volumio[1074]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:30 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::volumioPause Nov 04 22:34:30 volumio volumio[1074]: info: CoreStateMachine::pause Nov 04 22:34:30 volumio volumio[1074]: info: mpdhttpout --- Volumio set on pause Nov 04 22:34:30 volumio sudo[8488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 04 22:34:30 volumio sudo[8488]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:30 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 04 22:34:30 volumio sudo[8480]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 04 22:34:30 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 04 22:34:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 04 22:34:30 volumio volumio[1074]: info: Executing endpoint qc_getconfig Nov 04 22:34:30 volumio sudo[8488]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.576 [8486.8486] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 04 22:34:30 volumio volumio[1074]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.578 [8486.8486] INFO VolumeManager: [0xd1e8e0]: Setting new playback volume: 75 Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.578 [8486.8486] INFO VolumeManager: [0xd1e8e0]: Setting new mute state: 0 Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.578 [8486.8486] INFO QobuzConnect: [0xd1f2b0]: Client initialized! Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.578 [8486.8486] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 04 22:34:30 volumio volumio[1074]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 04 22:34:30 volumio volumio[1074]: info: Shairport-Sync Started Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.587 [8486.8486] INFO LocalConfigManager: [0xd1e1c0]: Starting Local Configuration server Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.587 [8486.8486] INFO SampleApp: Starting Local configuration server Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.587 [8486.8486] INFO SampleApp: Connected to UNIX socket client 0xd13f60 Nov 04 22:34:30 volumio mpd[8477]: Nov 04 22:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 22:34:30 volumio systemd[1]: Started Music Player Daemon. Nov 04 22:34:30 volumio sudo[8456]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:30 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:34:30 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:30 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:30 volumio qobuz-connect[8486]: 20251104 22:34:30.726 [8486.8486] INFO SampleApp: Playback volume changed: 75 Nov 04 22:34:30 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:30 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:30 volumio volumio[8499]: cset: --> shielding system active with Nov 04 22:34:30 volumio volumio[8499]: cset: "system" cpuset of CPUSPEC(1-3) with 147 tasks running Nov 04 22:34:30 volumio volumio[8499]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 04 22:34:30 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:31 volumio volumio[1074]: info: Stopping peppymeterbasic service Nov 04 22:34:31 volumio volumio[1074]: info: CoreStateMachine::stop Nov 04 22:34:31 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:34:31 volumio volumio[1074]: info: Disabling plugin peppymeterbasic Nov 04 22:34:31 volumio volumio[1074]: info: Preparing to generate the ALSA configuration file Nov 04 22:34:31 volumio sudo[8515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 04 22:34:31 volumio sudo[8515]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio volumio[1074]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 04 22:34:31 volumio volumio[1074]: info: Reading ALSA contributions from plugins. Nov 04 22:34:31 volumio volumio[1074]: info: Asound.conf file written Nov 04 22:34:31 volumio sudo[8515]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio sudo[8518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 04 22:34:31 volumio sudo[8518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio sudo[8518]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio volumio[1074]: xcb_connection_has_error() returned true Nov 04 22:34:31 volumio volumio[1074]: info: Output device has changed, restarting MPD Nov 04 22:34:31 volumio sudo[8523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 04 22:34:31 volumio sudo[8523]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio sudo[8523]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 22:34:31 volumio sudo[8526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 22:34:31 volumio sudo[8526]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 04 22:34:31 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:31 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:31 volumio systemd[1]: Stopping Music Player Daemon... Nov 04 22:34:31 volumio volumio[1074]: xcb_connection_has_error() returned true Nov 04 22:34:31 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 04 22:34:31 volumio volumio[1074]: xcb_connection_has_error() returned true Nov 04 22:34:31 volumio volumio[1074]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio systemd[1]: mpd.service: Succeeded. Nov 04 22:34:31 volumio systemd[1]: Stopped Music Player Daemon. Nov 04 22:34:31 volumio volumio[1074]: info: QobuzConnect: setDeactiveState invoked Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:31 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:31 volumio systemd[1]: Starting Music Player Daemon... Nov 04 22:34:31 volumio volumio[1074]: info: Done. Nov 04 22:34:31 volumio sudo[8535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 04 22:34:31 volumio sudo[8535]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio sudo[8531]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 22:34:31 volumio sudo[8531]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio sudo[8535]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio sudo[8538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 04 22:34:31 volumio sudo[8538]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio sudo[8531]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio sudo[8538]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:31 volumio sudo[8548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 04 22:34:31 volumio sudo[8548]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio volumio[1074]: info: MPD Permissions set Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio sudo[8548]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 22:34:31 volumio sudo[8551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 22:34:31 volumio sudo[8551]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio volumio[1074]: info: Starting Shairport Sync Nov 04 22:34:31 volumio qobuz-connect[8486]: 20251104 22:34:31.391 [8486.8486] INFO SampleApp: Stopping Local configuration server Nov 04 22:34:31 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 04 22:34:31 volumio volumio[1074]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:31 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::volumioPause Nov 04 22:34:31 volumio volumio[1074]: info: CoreStateMachine::pause Nov 04 22:34:31 volumio volumio[1074]: info: mpdhttpout --- Volumio set on pause Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:31 volumio qobuz-connect[8486]: 20251104 22:34:31.401 [8486.8486] INFO SampleApp: shat down connection on UNIX socket Nov 04 22:34:31 volumio volumio[1074]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 04 22:34:31 volumio volumio[1074]: info: QobuzConnect: setDeactiveState invoked Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:31 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:31 volumio sudo[8557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 04 22:34:31 volumio sudo[8557]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:31 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 04 22:34:31 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 04 22:34:31 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 04 22:34:31 volumio sudo[8551]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 04 22:34:31 volumio sudo[8557]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio volumio[1074]: info: Executing endpoint qc_getconfig Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.468 [8559.8559] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.470 [8559.8559] INFO VolumeManager: [0x16488e0]: Setting new playback volume: 75 Nov 04 22:34:31 volumio volumio[1074]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.471 [8559.8559] INFO VolumeManager: [0x16488e0]: Setting new mute state: 0 Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.471 [8559.8559] INFO QobuzConnect: [0x16492b0]: Client initialized! Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.471 [8559.8559] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.473 [8559.8559] INFO LocalConfigManager: [0x16481c0]: Starting Local Configuration server Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.474 [8559.8559] INFO SampleApp: Starting Local configuration server Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.474 [8559.8559] INFO SampleApp: Connected to UNIX socket client 0x163df60 Nov 04 22:34:31 volumio volumio[1074]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 04 22:34:31 volumio volumio[1074]: info: Shairport-Sync Started Nov 04 22:34:31 volumio mpd[8543]: Nov 04 22:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 22:34:31 volumio systemd[1]: Started Music Player Daemon. Nov 04 22:34:31 volumio sudo[8526]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:31 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:34:31 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:31 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:31 volumio qobuz-connect[8559]: 20251104 22:34:31.619 [8559.8559] INFO SampleApp: Playback volume changed: 75 Nov 04 22:34:31 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:31 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:31 volumio volumio[8568]: cset: --> shielding system active with Nov 04 22:34:31 volumio volumio[8568]: cset: "system" cpuset of CPUSPEC(1-3) with 151 tasks running Nov 04 22:34:31 volumio volumio[8568]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 04 22:34:31 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:32 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 04 22:34:32 volumio sudo[8584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 22:34:32 volumio sudo[8584]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:32 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 04 22:34:32 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:32 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:32 volumio systemd[1]: Stopping Music Player Daemon... Nov 04 22:34:32 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:32 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:32 volumio systemd[1]: mpd.service: Succeeded. Nov 04 22:34:32 volumio systemd[1]: Stopped Music Player Daemon. Nov 04 22:34:32 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:32 volumio systemd[1]: Starting Music Player Daemon... Nov 04 22:34:32 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:32 volumio sudo[8586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 22:34:32 volumio sudo[8586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:32 volumio sudo[8586]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:32 volumio mpd[8588]: Nov 04 22:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 22:34:32 volumio systemd[1]: Started Music Player Daemon. Nov 04 22:34:32 volumio sudo[8584]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:32 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:34:32 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:32 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:33 volumio volumio[8591]: cset: --> shielding system active with Nov 04 22:34:33 volumio volumio[8591]: cset: "system" cpuset of CPUSPEC(1-3) with 151 tasks running Nov 04 22:34:33 volumio volumio[8591]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 04 22:34:33 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:33 volumio volumio[1074]: info: TidalConnect service stoped! Nov 04 22:34:33 volumio volumio[1074]: info: TidalConnect service stoped! Nov 04 22:34:33 volumio volumio[1074]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 04 22:34:33 volumio volumio[1074]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 04 22:34:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 04 22:34:33 volumio sudo[8618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 04 22:34:33 volumio sudo[8618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:33 volumio sudo[8621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 22:34:33 volumio sudo[8621]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:33 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 04 22:34:33 volumio sudo[8618]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:33 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 04 22:34:33 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:33 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:33 volumio systemd[1]: Stopping Music Player Daemon... Nov 04 22:34:33 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:33 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:33 volumio systemd[1]: mpd.service: Succeeded. Nov 04 22:34:33 volumio systemd[1]: Stopped Music Player Daemon. Nov 04 22:34:33 volumio systemd[1]: Starting Music Player Daemon... Nov 04 22:34:33 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:34:33 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:33 volumio sudo[8626]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 22:34:33 volumio sudo[8626]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:33 volumio sudo[8626]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:33 volumio volumio[1074]: info: Executing endpoint tc_getconfig Nov 04 22:34:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 04 22:34:33 volumio vtcs[8622]: STARTING TidalConnect services, version: 1.5.1-beta1 Nov 04 22:34:33 volumio vtcs[8622]: STARTED TidalConnect services. Nov 04 22:34:33 volumio volumio[1074]: info: Executing endpoint tc_connect Nov 04 22:34:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 04 22:34:33 volumio volumio[1074]: info: Connecting to TidalConnect Nov 04 22:34:33 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:33 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:33 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 04 22:34:33 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:34:33 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:33 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 04 22:34:33 volumio mpd[8628]: Nov 04 22:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 22:34:33 volumio systemd[1]: Started Music Player Daemon. Nov 04 22:34:33 volumio sudo[8621]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:33 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:34:33 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 04 22:34:33 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:34:33 volumio volumio[8647]: cset: --> shielding system active with Nov 04 22:34:33 volumio volumio[8647]: cset: "system" cpuset of CPUSPEC(1-3) with 165 tasks running Nov 04 22:34:33 volumio volumio[8647]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 04 22:34:33 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 04 22:34:34 volumio volumio[1074]: info: TidalConnect service stoped! Nov 04 22:34:34 volumio volumio[1074]: info: TidalConnect service stoped! Nov 04 22:34:34 volumio volumio[1074]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 04 22:34:34 volumio volumio[1074]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 04 22:34:34 volumio sudo[8671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 04 22:34:34 volumio sudo[8671]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:34 volumio sudo[8671]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:35 volumio volumio[1074]: info: Disabling plugin rotaryencoder2 Nov 04 22:34:35 volumio volumio[1074]: info: Done. Nov 04 22:34:36 volumio volumio[1074]: info: TidalConnect service started! Nov 04 22:34:37 volumio volumio[1074]: info: TidalConnect service started! Nov 04 22:34:38 volumio volumio[1074]: info: Starting Uninstall of plugin system_hardware - rotaryencoder2 Nov 04 22:34:38 volumio volumio[1074]: info: Uninstalling plugin rotaryencoder2 Nov 04 22:34:38 volumio volumio[1074]: info: Disabling plugin rotaryencoder2 Nov 04 22:34:38 volumio volumio[1074]: info: Checking if uninstall.sh is present Nov 04 22:34:38 volumio volumio[1074]: info: Executing uninstall.sh Nov 04 22:34:38 volumio sudo[8677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/rotaryencoder2/uninstall.sh Nov 04 22:34:38 volumio sudo[8677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:38 volumio sudo[8677]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:38 volumio volumio[1074]: info: Uninstall script completed Nov 04 22:34:38 volumio volumio[1074]: info: Removing plugin rotaryencoder2 from configuration Nov 04 22:34:38 volumio volumio[1074]: info: Successfully removed rotaryencoder2 configuration files Nov 04 22:34:38 volumio volumio[1074]: info: Plugin folders cleanup Nov 04 22:34:38 volumio volumio[1074]: info: Scanning into folder /volumio/app/plugins/ Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category audio_interface Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category miscellanea Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category music_service Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category plugins.json Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category system_controller Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category user_interface Nov 04 22:34:38 volumio volumio[1074]: info: Scanning into folder /data/plugins/ Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category audio_interface Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category music_service Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category system_controller Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category system_hardware Nov 04 22:34:38 volumio volumio[1074]: info: Cleaning folder for rotaryencoder2 Nov 04 22:34:38 volumio volumio[1074]: info: Scanning category user_interface Nov 04 22:34:38 volumio volumio[1074]: info: Plugin folders cleanup completed Nov 04 22:34:38 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 22:34:38 volumio volumio[1074]: info: [{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","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":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_hardware","version":"0.8.4","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_hardware","version":"1.0.10","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.3","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false}] Nov 04 22:34:38 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 22:34:39 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:39 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:47 volumio volumio[1074]: info: Starting Uninstall of plugin system_hardware - gpio_control Nov 04 22:34:47 volumio volumio[1074]: info: Uninstalling plugin gpio_control Nov 04 22:34:47 volumio volumio[1074]: info: [GPIO_Control] GPIOs destroyed Nov 04 22:34:47 volumio volumio[1074]: info: Disabling plugin gpio_control Nov 04 22:34:47 volumio volumio[1074]: info: Checking if uninstall.sh is present Nov 04 22:34:47 volumio volumio[1074]: info: Executing uninstall.sh Nov 04 22:34:47 volumio sudo[8699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/gpio_control/uninstall.sh Nov 04 22:34:47 volumio sudo[8699]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:47 volumio sudo[8699]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:47 volumio volumio[1074]: info: Uninstall script completed Nov 04 22:34:47 volumio volumio[1074]: info: Removing plugin gpio_control from configuration Nov 04 22:34:47 volumio volumio[1074]: info: Successfully removed gpio_control configuration files Nov 04 22:34:47 volumio volumio[1074]: info: Plugin folders cleanup Nov 04 22:34:47 volumio volumio[1074]: info: Scanning into folder /volumio/app/plugins/ Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category audio_interface Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category miscellanea Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category music_service Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category plugins.json Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category system_controller Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category user_interface Nov 04 22:34:47 volumio volumio[1074]: info: Scanning into folder /data/plugins/ Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category audio_interface Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category music_service Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category system_controller Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category system_hardware Nov 04 22:34:47 volumio volumio[1074]: info: Cleaning folder for gpio_control Nov 04 22:34:47 volumio volumio[1074]: info: Scanning category user_interface Nov 04 22:34:47 volumio volumio[1074]: info: Plugin folders cleanup completed Nov 04 22:34:47 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 22:34:47 volumio volumio[1074]: info: [{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","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":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_hardware","version":"0.8.4","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.3","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false}] Nov 04 22:34:47 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 22:34:49 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:49 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:34:49 volumio volumio[1074]: info: Listing playlists Nov 04 22:34:49 volumio volumio[1074]: info: Listing playlists Nov 04 22:34:51 volumio volumio[1074]: info: Starting Uninstall of plugin system_hardware - gpio-buttons Nov 04 22:34:51 volumio volumio[1074]: info: Uninstalling plugin gpio-buttons Nov 04 22:34:51 volumio volumio[1074]: info: GPIO-Buttons stopped Nov 04 22:34:51 volumio volumio[1074]: info: Disabling plugin gpio-buttons Nov 04 22:34:51 volumio volumio[1074]: info: Checking if uninstall.sh is present Nov 04 22:34:51 volumio volumio[1074]: info: Executing uninstall.sh Nov 04 22:34:51 volumio sudo[8721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/gpio-buttons/uninstall.sh Nov 04 22:34:51 volumio sudo[8721]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:34:51 volumio sudo[8721]: pam_unix(sudo:session): session closed for user root Nov 04 22:34:51 volumio volumio[1074]: info: Uninstall script completed Nov 04 22:34:51 volumio volumio[1074]: info: Removing plugin gpio-buttons from configuration Nov 04 22:34:51 volumio volumio[1074]: info: Successfully removed gpio-buttons configuration files Nov 04 22:34:51 volumio volumio[1074]: info: Plugin folders cleanup Nov 04 22:34:51 volumio volumio[1074]: info: Scanning into folder /volumio/app/plugins/ Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category audio_interface Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category miscellanea Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category music_service Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category plugins.json Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category system_controller Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category user_interface Nov 04 22:34:51 volumio volumio[1074]: info: Scanning into folder /data/plugins/ Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category audio_interface Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category music_service Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category system_controller Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category system_hardware Nov 04 22:34:51 volumio volumio[1074]: info: Cleaning folder for gpio-buttons Nov 04 22:34:51 volumio volumio[1074]: info: Scanning category user_interface Nov 04 22:34:51 volumio volumio[1074]: info: Plugin folders cleanup completed Nov 04 22:34:51 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 22:34:51 volumio volumio[1074]: info: [{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","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":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.3","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false}] Nov 04 22:34:51 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 04 22:34:56 volumio volumio[1074]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 04 22:34:56 volumio volumio[1074]: info: Uninstalling plugin ampswitch Nov 04 22:34:56 volumio volumio[1074]: info: [ASDebug] Port: 24 Nov 04 22:34:56 volumio volumio[1074]: info: [ASDebug] Inverted: false Nov 04 22:34:56 volumio volumio[1074]: info: [ASDebug] Delay: 720 Nov 04 22:34:59 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:34:59 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 04 22:35:02 volumio volumio[1074]: info: Disabling plugin mpdoutput Nov 04 22:35:02 volumio volumio[1074]: info: Done. Nov 04 22:35:02 volumio sudo[8748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 22:35:02 volumio sudo[8748]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:35:02 volumio systemd[1]: musicservicesshield.service: Succeeded. Nov 04 22:35:02 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 04 22:35:02 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 04 22:35:02 volumio systemd[1]: Stopping Music Player Daemon... Nov 04 22:35:02 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:35:02 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:35:02 volumio systemd[1]: mpd.service: Succeeded. Nov 04 22:35:02 volumio systemd[1]: Stopped Music Player Daemon. Nov 04 22:35:02 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 04 22:35:02 volumio systemd[1]: Starting Music Player Daemon... Nov 04 22:35:02 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:35:02 volumio sudo[8750]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 22:35:02 volumio sudo[8750]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 22:35:02 volumio sudo[8750]: pam_unix(sudo:session): session closed for user root Nov 04 22:35:02 volumio mpd[8752]: Nov 04 22:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 22:35:02 volumio systemd[1]: Started Music Player Daemon. Nov 04 22:35:02 volumio sudo[8748]: pam_unix(sudo:session): session closed for user root Nov 04 22:35:02 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:35:02 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 04 22:35:02 volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 22:35:02 volumio volumio[8755]: cset: --> shielding system active with Nov 04 22:35:02 volumio volumio[8755]: cset: "system" cpuset of CPUSPEC(1-3) with 157 tasks running Nov 04 22:35:02 volumio volumio[8755]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 04 22:35:02 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 04 22:35:07 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:35:07 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::clearAddPlayTracks USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 04 22:35:08 volumio volumio[1074]: info: Nov 04 22:35:08 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:35:08 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf" Nov 04 22:35:08 volumio volumio[1074]: error: updateQueue error: null Nov 04 22:35:08 volumio volumio[1074]: info: ------------------------------ 1ms Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand add "USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf" took 1 milliseconds Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 04 22:35:08 volumio volumio[1074]: info: Nov 04 22:35:08 volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 04 22:35:08 volumio volumio[1074]: info: Ignoring MPD Status Update Nov 04 22:35:08 volumio volumio[1074]: info: Nov 04 22:35:08 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand play took 6 milliseconds Nov 04 22:35:08 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:35:08 volumio volumio[1074]: info: Nov 04 22:35:08 volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 04 22:35:08 volumio volumio[1074]: info: ------------------------------ 94ms Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand status took 88 milliseconds Nov 04 22:35:08 volumio volumio[1074]: info: ControllerMpd::getState Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand status took 0 milliseconds Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:35:08 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"45158 Kbps","isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:35:08 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 04 22:35:08 volumio volumio[1074]: info: ------------------------------ 91ms Nov 04 22:35:08 volumio volumio[1074]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 04 22:35:08 volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 04 22:35:08 volumio volumio[1074]: info: ControllerMpd::pushState Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238,"duration":316,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"45158 Kbps","isStreaming":false,"title":"SUGAR IN MY BOWL","artist":"POLLY GIBBONS","album":"BALLADS AND BLUES","uri":"USB/Polly Gibbons - Ballads and Blues (NativeDSD, 2023)(DSD512)/08. SUGAR IN MY BOWL.dsf","trackType":"dsf"} Nov 04 22:35:08 volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus play Nov 04 22:35:08 volumio volumio[1074]: info: Received an update from plugin. extracting info from payload Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:35:08 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:35:08 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreStateMachine::pushState Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 04 22:35:08 volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 04 22:35:08 volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 04 22:35:08 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 04 22:35:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 04 22:35:08 volumio volumio[1074]: info: ------------------------------ 7ms Nov 04 22:35:08 volumio volumio[1074]: info: [ASDebug] CurState: play PrevState: stop Nov 04 22:35:08 volumio volumio[1074]: info: [ASDebug] Togle GPIO: ON Nov 04 22:35:08 volumio volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 22:35:08 volumio volumio[1074]: Error: EBADF: bad file descriptor, write Nov 04 22:35:08 volumio volumio[1074]: at Object.writeSync (fs.js:711:3) Nov 04 22:35:08 volumio volumio[1074]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 04 22:35:08 volumio volumio[1074]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Nov 04 22:35:08 volumio volumio[1074]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 04 22:35:08 volumio volumio[1074]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 04 22:35:08 volumio volumio[1074]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 04 22:35:08 volumio volumio[1074]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 04 22:35:08 volumio volumio[1074]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 04 22:35:08 volumio volumio[1074]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 04 22:35:08 volumio volumio[1074]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 04 22:35:08 volumio volumio[1074]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 04 22:35:08 volumio volumio[1074]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 04 22:35:08 volumio volumio[1074]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 04 22:35:08 volumio volumio[1074]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 04 22:35:08 volumio volumio[1074]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 04 22:35:08 volumio volumio[1074]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 04 22:35:08 volumio volumio[1074]: errno: -9, Nov 04 22:35:08 volumio volumio[1074]: syscall: 'write', Nov 04 22:35:08 volumio volumio[1074]: code: 'EBADF' Nov 04 22:35:08 volumio volumio[1074]: } Nov 04 22:35:08 volumio volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 22:35:08 volumio sudo[8782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 22:34 Nov 04 22:35:08 volumio sudo[8782]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"