Feb 18 15:53:01 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::ClearQueue Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::stop Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::stPlaybackTimer Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::serviceStop Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::serviceStop Feb 18 15:53:01 red-volumio volumio[1009]: info: [1771426381592] ControllerWebradio::stop Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::clearPlayQueue Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::saveQueue Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::addQueueItems Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::addQueueItems Feb 18 15:53:01 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:01 red-volumio volumio[1009]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 18 15:53:01 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:01.593+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume=96 Feb 18 15:53:01 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:01.594+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://icecast.radiofrance.fr/franceculture-midfi.mp3 title="France Culture Live" Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::saveQueue Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPlay Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::play index 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::stop Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::play index undefined Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Feb 18 15:53:01 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 18 15:53:01 red-volumio volumio[1009]: info: [1771426381598] ControllerWebradio::clearAddPlayTrack Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:01 red-volumio volumio[1009]: info: Feb 18 15:53:01 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand stop took 29 milliseconds Feb 18 15:53:01 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand stop took 24 milliseconds Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand status took 0 milliseconds Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:01 red-volumio volumio[1009]: info: Feb 18 15:53:01 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:01 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand clear took 1 milliseconds Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:01 red-volumio volumio[1009]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 18 15:53:01 red-volumio volumio[1009]: info: ------------------------------ 3ms Feb 18 15:53:01 red-volumio volumio[1009]: error: updateQueue error: null Feb 18 15:53:01 red-volumio volumio[1009]: info: ------------------------------ 3ms Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Feb 18 15:53:01 red-volumio volumio[1009]: info: Feb 18 15:53:01 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:01 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 1 milliseconds Feb 18 15:53:01 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 15:53:01 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Feb 18 15:53:01 red-volumio volumio[1009]: info: ------------------------------ 1ms Feb 18 15:53:01 red-volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Feb 18 15:53:02 red-volumio volumio[1009]: info: Feb 18 15:53:02 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:02 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:02 red-volumio volumio[1009]: info: sendMpdCommand status took 19 milliseconds Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:02 red-volumio volumio[1009]: info: Feb 18 15:53:02 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:02 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:02 red-volumio volumio[1009]: info: Feb 18 15:53:02 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:02 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:02 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:02 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:02 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"95 Kbps","isStreaming":false,"title":"B.B. King & Mick Hucknall - Please Send Me Someone To Love","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Feb 18 15:53:02 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Feb 18 15:53:02 red-volumio volumio[1009]: info: ------------------------------ 21ms Feb 18 15:53:02 red-volumio volumio[1009]: info: ------------------------------ 1ms Feb 18 15:53:02 red-volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:02 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 15:53:02 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:02 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:02 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":358,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"95 Kbps","isStreaming":false,"title":"B.B. King & Mick Hucknall - Please Send Me Someone To Love","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Feb 18 15:53:02 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:02 red-volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:02 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:02 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:02.366+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=96 Feb 18 15:53:02 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:02.367+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="B.B. King & Mick Hucknall - Please Send Me Someone To Love" Feb 18 15:53:02 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:02.367+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=96 Feb 18 15:53:02 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:02.368+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="B.B. King & Mick Hucknall - Please Send Me Someone To Love" Feb 18 15:53:02 red-volumio volumio[1009]: info: ------------------------------ 12ms Feb 18 15:53:04 red-volumio volumio[1009]: info: Executing endpoint metavolumio Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 18 15:53:04 red-volumio volumio[1009]: info: Executing endpoint metavolumio Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 18 15:53:04 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::ClearQueue Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::stop Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::stPlaybackTimer Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::serviceStop Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::serviceStop Feb 18 15:53:04 red-volumio volumio[1009]: info: [1771426384439] ControllerWebradio::stop Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::clearPlayQueue Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::saveQueue Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::addQueueItems Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::addQueueItems Feb 18 15:53:04 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:04 red-volumio volumio[1009]: info: Adding Item to queue: http://media-sov.musicradio.com:80/RadioXLondonMP3 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.440+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume=96 Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.440+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::saveQueue Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPlay Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::play index 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::stop Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::play index undefined Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 18 15:53:04 red-volumio volumio[1009]: info: [1771426384445] ControllerWebradio::clearAddPlayTrack Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand stop took 30 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand stop took 25 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:04 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand clear took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand load "http://media-sov.musicradio.com:80/RadioXLondonMP3" Feb 18 15:53:04 red-volumio volumio[1009]: error: updateQueue error: null Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 1ms Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:04 red-volumio volumio[1009]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 3ms Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:04 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 1ms Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand status took 43 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:04 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:04 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:04 red-volumio volumio[1009]: info: Feb 18 15:53:04 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gorillaz - Orange County","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Feb 18 15:53:04 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 46ms Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 7ms Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 6ms Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand status took 6 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":78,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gorillaz - Orange County","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Feb 18 15:53:04 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:04 red-volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.641+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=38 volume=96 Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.642+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Gorillaz - Orange County" Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.642+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=38 volume=96 Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.642+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Gorillaz - Orange County" Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 15ms Feb 18 15:53:04 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 18 15:53:04 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:04 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:04 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gorillaz - Orange County","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Feb 18 15:53:04 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:04 red-volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:04 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.648+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=38 volume=96 Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.649+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=38 volume=96 Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.649+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Gorillaz - Orange County" Feb 18 15:53:04 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:04.649+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Gorillaz - Orange County" Feb 18 15:53:04 red-volumio volumio[1009]: info: ------------------------------ 21ms Feb 18 15:53:06 red-volumio volumio[1009]: info: Executing endpoint metavolumio Feb 18 15:53:06 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 18 15:53:06 red-volumio volumio[1009]: info: Executing endpoint metavolumio Feb 18 15:53:06 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 18 15:53:07 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1386. Feb 18 15:53:07 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:07 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:07 red-volumio upmpdcli[12361]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:53:07 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:53:07 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:53:17 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 18 15:53:17 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:22 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1387. Feb 18 15:53:22 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:22 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:22 red-volumio upmpdcli[12377]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:53:22 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:53:22 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:53:26 red-volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 18 15:53:27 red-volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 18 15:53:27 red-volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 18 15:53:37 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 18 15:53:37 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1388. Feb 18 15:53:37 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:38 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:38 red-volumio upmpdcli[12431]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:53:38 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:53:38 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:53:38 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:38 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::ClearQueue Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::stop Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::stPlaybackTimer Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::serviceStop Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::serviceStop Feb 18 15:53:38 red-volumio volumio[1009]: info: [1771426418994] ControllerWebradio::stop Feb 18 15:53:38 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::clearPlayQueue Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::saveQueue Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreStateMachine::addQueueItems Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::addQueueItems Feb 18 15:53:38 red-volumio volumio[1009]: info: Preload queue cleared Feb 18 15:53:38 red-volumio volumio[1009]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 18 15:53:38 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:38.996+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume=96 Feb 18 15:53:38 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:38.996+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" Feb 18 15:53:38 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:38 red-volumio volumio[1009]: info: CorePlayQueue::saveQueue Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPlay Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::play index 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::stop Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::play index undefined Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 18 15:53:39 red-volumio volumio[1009]: info: [1771426419001] ControllerWebradio::clearAddPlayTrack Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand stop took 35 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand stop took 28 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:39 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand clear took 1 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Feb 18 15:53:39 red-volumio volumio[1009]: error: updateQueue error: null Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 1ms Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:39 red-volumio volumio[1009]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 3ms Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 206 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:39 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 1ms Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand status took 30 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces system playlist update Feb 18 15:53:39 red-volumio volumio[1009]: info: Ignoring MPD Status Update Feb 18 15:53:39 red-volumio volumio[1009]: info: Feb 18 15:53:39 red-volumio volumio[1009]: ---------------------------- MPD announces state update: player Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::getState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:39 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 18 15:53:39 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 33ms Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 2ms Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseState Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:39 red-volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:39 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 18 15:53:39 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:39 red-volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:39 red-volumio volumio[1009]: info: ControllerMpd::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::servicePushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:39 red-volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 18 15:53:39 red-volumio volumio[1009]: verbose: CURRENT POSITION 0 Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:39 red-volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreStateMachine::pushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:39 red-volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.688+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=96 Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=96 Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=96 Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=96 Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Feb 18 15:53:39 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:39.689+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 16ms Feb 18 15:53:39 red-volumio volumio[1009]: info: ------------------------------ 16ms Feb 18 15:53:40 red-volumio volumio[1009]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:53:40 red-volumio volumio[1009]: TypeError: Cannot read properties of null (reading 'slice') Feb 18 15:53:40 red-volumio volumio[1009]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 18 15:53:40 red-volumio volumio[1009]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 18 15:53:40 red-volumio volumio[1009]: at Object.onceWrapper (node:events:629:26) Feb 18 15:53:40 red-volumio volumio[1009]: at ClientRequest.emit (node:events:514:28) Feb 18 15:53:40 red-volumio volumio[1009]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Feb 18 15:53:40 red-volumio volumio[1009]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Feb 18 15:53:40 red-volumio volumio[1009]: at TLSSocket.socketOnData (node:_http_client:535:22) Feb 18 15:53:40 red-volumio volumio[1009]: at TLSSocket.emit (node:events:514:28) Feb 18 15:53:40 red-volumio volumio[1009]: at addChunk (node:internal/streams/readable:343:12) Feb 18 15:53:40 red-volumio volumio[1009]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 18 15:53:40 red-volumio volumio[1009]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:53:40 red-volumio sudo[12450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 15:52' Feb 18 15:53:40 red-volumio sudo[12450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:40 red-volumio sudo[12450]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:40 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:40.904+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 18 15:53:40 red-volumio qobuz-connect[4410]: 20260218 15:53:40.904 [4410.4410] INFO SampleApp: Socket closed Feb 18 15:53:40 red-volumio volumio-remote-updater[784]: [2026-02-18 15:53:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 15:53:40 red-volumio volumio-remote-updater[784]: [2026-02-18 15:53:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 15:53:40 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:40.906+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 18 15:53:40 red-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:53:40 red-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 15:53:40 red-volumio systemd[1]: volumio.service: Consumed 5h 55min 59.224s CPU time. Feb 18 15:53:40 red-volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 15:53:40 red-volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 15:53:41 red-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 18 15:53:41 red-volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 15:53:41 red-volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 15:53:41 red-volumio systemd[1]: volumio.service: Consumed 5h 55min 59.224s CPU time. Feb 18 15:53:41 red-volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 15:53:41 red-volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 15:53:41 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:41.907+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 18 15:53:42 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:42 red-volumio volumio[12498]: info: ----- Volumio3 ---- Feb 18 15:53:42 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:42 red-volumio volumio[12498]: info: ----- System startup ---- Feb 18 15:53:42 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:42 red-volumio volumio[12498]: info: MYVOLUMIO Environment detected Feb 18 15:53:42 red-volumio volumio[12498]: info: Plugin folders cleanup Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning into folder /volumio/app/plugins/ Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category audio_interface Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category miscellanea Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category music_service Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category plugins.json Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category system_controller Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category user_interface Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning into folder /data/plugins/ Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category music_service Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category system_hardware Feb 18 15:53:42 red-volumio volumio[12498]: info: Scanning category user_interface Feb 18 15:53:42 red-volumio volumio[12498]: info: Plugin folders cleanup completed Feb 18 15:53:42 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:42 red-volumio volumio[12498]: info: ----- Core plugins startup ---- Feb 18 15:53:42 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:42 red-volumio volumio[12498]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 15:53:42 red-volumio volumio[12498]: info: Adding plugin upnp to MyMusic Plugins Feb 18 15:53:42 red-volumio volumio[12498]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 15:53:42 red-volumio volumio[12498]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 15:53:42 red-volumio volumio[12498]: info: Loading plugins from folder /data/plugins/ Feb 18 15:53:42 red-volumio volumio[12498]: info: Loading plugin "system"... Feb 18 15:53:42 red-volumio volumio[12498]: info: Loading plugin "appearance"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "network"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Refreshing Cached IP Addresses Feb 18 15:53:43 red-volumio sudo[12526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 15:53:43 red-volumio sudo[12526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:43 red-volumio sudo[12528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 15:53:43 red-volumio sudo[12528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:43 red-volumio sudo[12526]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "services"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "volumio5onboarding"... Feb 18 15:53:43 red-volumio sudo[12541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 15:53:43 red-volumio sudo[12541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:43 red-volumio sudo[12528]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "alsa_controller"... Feb 18 15:53:43 red-volumio sudo[12541]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:43 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "wizard"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "networkfs"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Starting Udev Watcher for removable devices Feb 18 15:53:43 red-volumio volumio[12498]: info: Ignoring mount for partition: boot Feb 18 15:53:43 red-volumio volumio[12498]: info: Ignoring mount for partition: volumio Feb 18 15:53:43 red-volumio volumio[12498]: info: Ignoring mount for partition: volumio_data Feb 18 15:53:43 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "volumio_command_line_client"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Plugin upnp is not enabled Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "my_music"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "mpd"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Plugin upnp_browser is not enabled Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "alarm-clock"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "airplay_emulation"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "last_100"... Feb 18 15:53:43 red-volumio volumio[12498]: info: Loading plugin "webradio"... Feb 18 15:53:44 red-volumio volumio[12498]: info: Loading plugin "i2s_dacs"... Feb 18 15:53:44 red-volumio volumio[12498]: info: Loading plugin "volumiodiscovery"... Feb 18 15:53:44 red-volumio volumio[12498]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 15:53:44 red-volumio volumio[12498]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:53:44 red-volumio volumio[12498]: *** WARNING *** For more information see Feb 18 15:53:44 red-volumio volumio[12498]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 15:53:44 red-volumio volumio[12498]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:53:44 red-volumio node[12498]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 15:53:44 red-volumio volumio[12498]: *** WARNING *** For more information see Feb 18 15:53:44 red-volumio node[12498]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:53:44 red-volumio node[12498]: *** WARNING *** For more information see Feb 18 15:53:44 red-volumio node[12498]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 15:53:44 red-volumio node[12498]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:53:44 red-volumio node[12498]: *** WARNING *** For more information see Feb 18 15:53:44 red-volumio volumio[12498]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 15:53:44 red-volumio volumio[12498]: info: Discovery: Started advertising with name: Red Volumio Feb 18 15:53:44 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:53:44 red-volumio volumio[12498]: info: Loading plugin "now_playing"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "outputs"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "albumart"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Plugin example_plugin is not enabled Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "inputs"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "updater_comm"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Plugin mpdemulation is not enabled Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "rest_api"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "websocket"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Starting Socket.io Server version 1.7.4 Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "radio_paradise"... Feb 18 15:53:45 red-volumio volumio[12498]: info: Applying required configuration parameters for plugin radio_paradise Feb 18 15:53:45 red-volumio volumio[12498]: info: [1771426425518] [RadioParadise] API delay: 5 Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading plugin "music_services_shield"... Feb 18 15:53:45 red-volumio volumio[12565]: Forking 3 albumart workers Feb 18 15:53:45 red-volumio volumio[12498]: info: Applying required configuration parameters for plugin music_services_shield Feb 18 15:53:45 red-volumio volumio[12498]: info: Loading i18n strings for locale nl Feb 18 15:53:45 red-volumio volumio[12498]: Updating browse sources language Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::initPlayerControls Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:53:45 red-volumio volumio[12498]: Express server listening on port 3000 Feb 18 15:53:45 red-volumio volumio[12498]: [Metrics] WebUI: 3s 738.80ms Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreStateMachine::resetVolumioState Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreStateMachine::getcurrentVolume Feb 18 15:53:45 red-volumio volumio[12498]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 15:53:45 red-volumio volumio[12498]: info: Volumio Network Manager: Network status updated: 1 Feb 18 15:53:45 red-volumio volumio[12498]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 18 15:53:45 red-volumio volumio[12498]: wlan0 Interface doesn't support scanning. Feb 18 15:53:45 red-volumio volumio[12498]: info: Cannot use regular scanning, forcing with ap-force Feb 18 15:53:45 red-volumio volumio-remote-updater[784]: [2026-02-18 15:53:45] [connect] Successful connection Feb 18 15:53:45 red-volumio sudo[12611]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 18 15:53:45 red-volumio sudo[12611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:45 red-volumio sudo[12611]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:45 red-volumio volumio[12498]: command failed: No such device (-19) Feb 18 15:53:45 red-volumio volumio[12498]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 18 15:53:45 red-volumio volumio[12498]: command failed: No such device (-19) Feb 18 15:53:46 red-volumio volumio[12498]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 18 15:53:46 red-volumio volumio[12498]: Unable to parse: Feb 18 15:53:46 red-volumio volumio[12498]: Simple mixer control 'Holo Audio UAC2.0 Gen2 - RED Playback Swit',0 Feb 18 15:53:46 red-volumio volumio[12498]: Capabilities: pswitch Feb 18 15:53:46 red-volumio volumio[12498]: Playback channels: Front Left - Front Right Feb 18 15:53:46 red-volumio volumio[12498]: Mono: Feb 18 15:53:46 red-volumio volumio[12498]: Front Left: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: Front Right: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 18 15:53:46 red-volumio volumio[12498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:46 red-volumio volumio[12498]: info: Reloading queue from file Feb 18 15:53:46 red-volumio volumio[12498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 18 15:53:46 red-volumio volumio[12498]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.55 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 18 15:53:46 red-volumio volumio[12498]: Unable to parse: Feb 18 15:53:46 red-volumio volumio[12498]: Simple mixer control 'Holo Audio UAC2.0 Gen2 - RED Playback Swit',0 Feb 18 15:53:46 red-volumio volumio[12498]: Capabilities: pswitch Feb 18 15:53:46 red-volumio volumio[12498]: Playback channels: Front Left - Front Right Feb 18 15:53:46 red-volumio volumio[12498]: Mono: Feb 18 15:53:46 red-volumio volumio[12498]: Front Left: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: Front Right: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: info: VolumeController:: Volume=undefined Mute =false Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.204+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume= Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.204+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::setRepeat null single undefined Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::setRandom null Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.221+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume= Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.222+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.226+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume= Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.226+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Feb 18 15:53:46 red-volumio volumio[12498]: info: Setting Device type: Raspberry PI Feb 18 15:53:46 red-volumio volumio[12498]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 18 15:53:46 red-volumio volumio-remote-updater[784]: [2026-02-18 15:53:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771426425 101 Feb 18 15:53:46 red-volumio volumio[12498]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Feb 18 15:53:46 red-volumio volumio[12498]: Unable to parse: Feb 18 15:53:46 red-volumio volumio[12498]: Simple mixer control 'Holo Audio UAC2.0 Gen2 - RED Playback Swit',0 Feb 18 15:53:46 red-volumio volumio[12498]: Capabilities: pswitch Feb 18 15:53:46 red-volumio volumio[12498]: Playback channels: Front Left - Front Right Feb 18 15:53:46 red-volumio volumio[12498]: Mono: Feb 18 15:53:46 red-volumio volumio[12498]: Front Left: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: Front Right: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 18 15:53:46 red-volumio volumio[12498]: info: Completed loading Core Plugins Feb 18 15:53:46 red-volumio volumio[12498]: info: Preparing to generate the ALSA configuration file Feb 18 15:53:46 red-volumio volumio[12498]: Unable to parse: Feb 18 15:53:46 red-volumio volumio[12498]: Simple mixer control 'Holo Audio UAC2.0 Gen2 - RED Playback Swit',0 Feb 18 15:53:46 red-volumio volumio[12498]: Capabilities: pswitch Feb 18 15:53:46 red-volumio volumio[12498]: Playback channels: Front Left - Front Right Feb 18 15:53:46 red-volumio volumio[12498]: Mono: Feb 18 15:53:46 red-volumio volumio[12498]: Front Left: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: Front Right: Playback [on] Feb 18 15:53:46 red-volumio volumio[12498]: info: VolumeController:: Volume=undefined Mute =false Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.333+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume= Feb 18 15:53:46 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:46.334+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Feb 18 15:53:46 red-volumio volumio[12498]: info: Discovery: adding 70f83063-c6aa-40c6-a83d-f888e62955a3 Feb 18 15:53:46 red-volumio volumio[12498]: info: Discovery: Found device Red Volumio Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:46 red-volumio volumio[12498]: info: Discovery: this is already registered, 70f83063-c6aa-40c6-a83d-f888e62955a3 Feb 18 15:53:46 red-volumio volumio[12498]: info: Discovery: Found device Red Volumio Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:46 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:46 red-volumio volumio[12498]: info: Asound.conf file unchanged, so no further update is needed Feb 18 15:53:46 red-volumio volumio[12498]: info: Output device has changed, restarting MPD Feb 18 15:53:46 red-volumio volumio[12498]: info: Output device has changed, restarting Shairport Sync Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:46 red-volumio sudo[12628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:53:46 red-volumio sudo[12628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:46 red-volumio sudo[12626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:53:46 red-volumio sudo[12626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:46 red-volumio sudo[12626]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:46 red-volumio volumio[12498]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:53:46 red-volumio volumio[12498]: info: ___________ START PLUGINS ___________ Feb 18 15:53:46 red-volumio volumio[12498]: info: ControllerMpd::onStart: Initializing MPD Feb 18 15:53:46 red-volumio volumio[12498]: info: Creating MPD Configuration file Feb 18 15:53:46 red-volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Feb 18 15:53:46 red-volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 18 15:53:46 red-volumio systemd[1]: musicservicesshield.service: Consumed 1.443s CPU time. Feb 18 15:53:46 red-volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 18 15:53:46 red-volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:46 red-volumio sudo[12636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 18 15:53:46 red-volumio sudo[12636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:46 red-volumio volumio[12498]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:46 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 15:53:46 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 15:53:46 red-volumio systemd[1]: mpd.service: Consumed 1min 46.682s CPU time. Feb 18 15:53:46 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 15:53:46 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 15:53:46 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 15:53:46 red-volumio sudo[12640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:53:46 red-volumio sudo[12640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:46 red-volumio sudo[12638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:53:46 red-volumio sudo[12638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:46 red-volumio volumio[12498]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:53:46 red-volumio volumio[12498]: info: [1771426426542] CoreMusicLibrary::Adding element Last_100 Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:46 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:53:46 red-volumio volumio[12498]: info: [1771426426549] CoreMusicLibrary::Adding element Webradio Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:46 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:53:46 red-volumio volumio[12498]: info: Initializing BBC Radios Feb 18 15:53:46 red-volumio sudo[12638]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:46 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 15:53:46 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 15:53:46 red-volumio sudo[12636]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:46 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 15:53:46 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 15:53:46 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 15:53:46 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 15:53:46 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 15:53:46 red-volumio volumio[12575]: Starting albumart workers Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:46 red-volumio volumio[12498]: info: [now-playing] ConfigUpdater: config is up to date. Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:53:46 red-volumio volumio[12498]: info: [1771426426796] CoreMusicLibrary::Adding element Radio Paradise Feb 18 15:53:46 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:46 red-volumio volumio[12498]: Cannot find translation for source Radio Paradise Feb 18 15:53:46 red-volumio sudo[12668]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:53:46 red-volumio sudo[12668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 15:53:46 red-volumio sudo[12668]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:47 red-volumio volumio[12577]: Starting albumart workers Feb 18 15:53:47 red-volumio volumio[12498]: info: Volumio Calling Home Feb 18 15:53:47 red-volumio sudo[12705]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Feb 18 15:53:47 red-volumio sudo[12705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:47 red-volumio volumio[12576]: Starting albumart workers Feb 18 15:53:47 red-volumio volumio[12498]: info: [now-playing] App is listening on port 4004. Feb 18 15:53:47 red-volumio volumio[12498]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 18 15:53:47 red-volumio volumio[12498]: info: MPD Permissions set Feb 18 15:53:47 red-volumio volumio[12498]: info: MPD Permissions set Feb 18 15:53:47 red-volumio volumio[12498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 18 15:53:47 red-volumio volumio[12498]: info: Volumio called home Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:53:48 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:53:48 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:53:48 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:53:48 red-volumio sudo[12751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:53:48 red-volumio sudo[12753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:53:48 red-volumio sudo[12751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:48 red-volumio sudo[12753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:48 red-volumio sudo[12754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:53:48 red-volumio sudo[12754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:48 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 15:53:48 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 15:53:48 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:53:48 red-volumio systemd[1]: shairport-sync.service: Consumed 19.044s CPU time. Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:48 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:48 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:53:48 red-volumio sudo[12751]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:48 red-volumio sudo[12753]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:48 red-volumio volumio[12498]: info: Shairport-Sync Started Feb 18 15:53:48 red-volumio volumio[12498]: Error adding Membership: Error: addMembership EINVAL Feb 18 15:53:48 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 15:53:48 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 15:53:48 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:53:48 red-volumio volumio[12498]: info: Shairport-Sync Started Feb 18 15:53:48 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:53:48 red-volumio sudo[12754]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:48 red-volumio volumio[12498]: info: Shairport-Sync Started Feb 18 15:53:48 red-volumio volumio[12498]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.55 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:48 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetQueue Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreStateMachine::getQueue Feb 18 15:53:48 red-volumio volumio[12498]: info: CorePlayQueue::getQueue Feb 18 15:53:48 red-volumio volumio[12498]: info: Listing playlists Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 15:53:48 red-volumio volumio[12498]: info: Received Get System Info Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:53:48 red-volumio volumio[12498]: info: Discovery: Getting this device information Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:48 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:48 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:48 red-volumio mpd[12675]: 2026-02-18T15:53:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 15:53:48 red-volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 15:53:48 red-volumio sudo[12628]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:48 red-volumio sudo[12640]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:48 red-volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 18 15:53:48 red-volumio volumio[12498]: error: MPD error: The expression evaluated to a falsy value: Feb 18 15:53:48 red-volumio volumio[12498]: assert.ok(self.idling) Feb 18 15:53:48 red-volumio volumio[12498]: error: The expression evaluated to a falsy value: Feb 18 15:53:48 red-volumio volumio[12498]: assert.ok(self.idling) Feb 18 15:53:48 red-volumio volumio[12498]: error: updateQueue error: null Feb 18 15:53:48 red-volumio volumio[12498]: info: VolumeController::SetAlsaVolume0 Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:48 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:48 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:48 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:48.919+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_STOPPED positionMs=0 volume=0 Feb 18 15:53:48 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:48.919+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Feb 18 15:53:48 red-volumio volumio[12498]: info: MPD running with PID12675 Feb 18 15:53:48 red-volumio volumio[12498]: ,establishing connection Feb 18 15:53:48 red-volumio volumio[12498]: error: updateQueue error: null Feb 18 15:53:49 red-volumio sudo[12705]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:49 red-volumio volumio[12498]: info: Completed starting Core Plugins Feb 18 15:53:49 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:49 red-volumio volumio[12498]: info: ----- MyVolumio plugins startup ---- Feb 18 15:53:49 red-volumio volumio[12498]: info: ------------------------------------------- Feb 18 15:53:49 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 15:53:49 red-volumio sudo[12794]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Feb 18 15:53:49 red-volumio sudo[12794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:49 red-volumio bash[12803]: cset: --> shielding system active with Feb 18 15:53:49 red-volumio bash[12803]: cset: "system" cpuset of CPUSPEC(1-3) with 144 tasks running Feb 18 15:53:49 red-volumio bash[12803]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 18 15:53:49 red-volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 18 15:53:49 red-volumio systemd[1]: Reloading. Feb 18 15:53:49 red-volumio volumio[12498]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.17 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 18 15:53:49 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:50 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:50 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 15:53:50 red-volumio volumio[12498]: info: Received Get System Info Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:53:50 red-volumio volumio[12498]: info: Discovery: Getting this device information Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:50 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:50 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:50 red-volumio volumio[12498]: info: Listing playlists Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetQueue Feb 18 15:53:50 red-volumio volumio[12498]: info: CoreStateMachine::getQueue Feb 18 15:53:50 red-volumio volumio[12498]: info: CorePlayQueue::getQueue Feb 18 15:53:50 red-volumio systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Feb 18 15:53:50 red-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 15:53:50 red-volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 15:53:50 red-volumio sudo[12794]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:50 red-volumio volumio[12498]: info: succeeded Feb 18 15:53:52 red-volumio volumio[12498]: info: Preload queue cleared Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::ClearQueue Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::stop Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::clearPlayQueue Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::saveQueue Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::addQueueItems Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::addQueueItems Feb 18 15:53:52 red-volumio volumio[12498]: info: Preload queue cleared Feb 18 15:53:52 red-volumio volumio[12498]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99616827 Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushQueue Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::saveQueue Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::updateTrackBlock Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::getTrackBlock Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPlay Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::play index 0 Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::stop Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::play index undefined Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:52 red-volumio volumio[12498]: info: CoreStateMachine::startPlaybackTimer Feb 18 15:53:52 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:52 red-volumio volumio[12498]: info: [1771426432998] ControllerWebradio::clearAddPlayTrack Feb 18 15:53:52 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand stop took 4 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand clear took 3 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99616827" Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: error: updateQueue error: null Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 6ms Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99616827" took 186 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand play Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 189ms Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 188ms Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand play took 7 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 7ms Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 4ms Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 3ms Feb 18 15:53:53 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1389. Feb 18 15:53:53 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:53 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:53:53 red-volumio upmpdcli[12829]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:53:53 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:53:53 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces state update: player Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::getState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces state update: player Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::getState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces state update: player Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::getState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand status took 33 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces state update: player Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::getState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces state update: player Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::getState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand status took 38 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand status took 37 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::servicePushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:53 red-volumio volumio[12498]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Milena Ceranic - Al Capone","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Feb 18 15:53:53 red-volumio volumio[12498]: verbose: CURRENT POSITION 0 Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState currentStatus stop Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 49ms Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces state update: player Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::getState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand status Feb 18 15:53:53 red-volumio volumio[12498]: info: Feb 18 15:53:53 red-volumio volumio[12498]: ---------------------------- MPD announces system playlist update Feb 18 15:53:53 red-volumio volumio[12498]: info: Ignoring MPD Status Update Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand status took 16 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 16ms Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand status took 14 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 5ms Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand status took 4 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 3ms Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseState Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::servicePushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:53 red-volumio volumio[12498]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Milena Ceranic - Al Capone","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Feb 18 15:53:53 red-volumio volumio[12498]: verbose: CURRENT POSITION 0 Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:53 red-volumio volumio[12498]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::servicePushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:53 red-volumio volumio[12498]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Milena Ceranic - Al Capone","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Feb 18 15:53:53 red-volumio volumio[12498]: verbose: CURRENT POSITION 0 Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:53 red-volumio volumio[12498]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.672+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.673+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.675+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.675+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.677+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.677+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.679+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.681+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 86ms Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 85ms Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:53 red-volumio volumio[12498]: verbose: ControllerMpd::parseTrackInfo Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::servicePushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:53 red-volumio volumio[12498]: verbose: STATE SERVICE {"status":"play","position":0,"seek":664,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Milena Ceranic - Al Capone","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Feb 18 15:53:53 red-volumio volumio[12498]: verbose: CURRENT POSITION 0 Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:53 red-volumio volumio[12498]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::servicePushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:53 red-volumio volumio[12498]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Milena Ceranic - Al Capone","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Feb 18 15:53:53 red-volumio volumio[12498]: verbose: CURRENT POSITION 0 Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:53 red-volumio volumio[12498]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: ControllerMpd::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::servicePushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CorePlayQueue::getTrack 0 Feb 18 15:53:53 red-volumio volumio[12498]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Milena Ceranic - Al Capone","artist":"OK Radio 94.2FM","album":null,"uri":"http://sslstream.okradio.net:8000/","trackType":"net:8000/"} Feb 18 15:53:53 red-volumio volumio[12498]: verbose: CURRENT POSITION 0 Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState stateService play Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::syncState currentStatus play Feb 18 15:53:53 red-volumio volumio[12498]: info: Received an update from plugin. extracting info from payload Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreStateMachine::pushState Feb 18 15:53:53 red-volumio volumio[12498]: info: CoreCommandRouter::volumioPushState Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.716+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.716+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.717+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.717+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.718+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.718+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.719+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.720+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.720+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.721+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.721+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" state=STATUS_PLAYING positionMs=0 volume=0 Feb 18 15:53:53 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:53.722+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" id=http://sslstream.okradio.net:8000/ title="Milena Ceranic - Al Capone" Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 98ms Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 96ms Feb 18 15:53:53 red-volumio volumio[12498]: info: ------------------------------ 86ms Feb 18 15:53:53 red-volumio volumio[12498]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 15:53:54 red-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 18 15:53:54 red-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 18 15:53:54 red-volumio systemd[1]: welcome.service: Deactivated successfully. Feb 18 15:53:54 red-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 18 15:53:54 red-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 18 15:53:54 red-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 18 15:53:54 red-volumio welcome[12863]: Resolved ip:[1] 192.168.1.177 Feb 18 15:53:54 red-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 18 15:53:54 red-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 18 15:53:55 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:53:55.064+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" available=true connected=true macAddress=e4:5f:01:e2:a6:d3 ip4Address=192.168.1.177/24 ip6Address= Feb 18 15:53:55 red-volumio volumio[12498]: info: Received Get System Info Feb 18 15:53:55 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:53:55 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:53:55 red-volumio volumio[12498]: info: Discovery: Getting this device information Feb 18 15:53:55 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:53:55 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:53:55 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:53:55 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 15:53:57 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 15:53:58 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:58 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:53:58 red-volumio volumio[12498]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 15:53:58 red-volumio volumio[12498]: info: MyVolumio login type: Token Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 15:53:58 red-volumio volumio[12498]: info: Streaming services startup Feb 18 15:53:58 red-volumio volumio[12498]: info: Starting Streaming Daemon Feb 18 15:53:58 red-volumio sudo[12871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 15:53:58 red-volumio sudo[12871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:53:58 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 15:53:58 red-volumio sudo[12871]: pam_unix(sudo:session): session closed for user root Feb 18 15:53:58 red-volumio volumio[12498]: error: Cannot start Volumio Streaming Daemon Feb 18 15:53:58 red-volumio volumio[12498]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 15:53:58 red-volumio volumio[12498]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 15:53:58 red-volumio volumio[12498]: error: MyVolumio Custom Token format not valid, refreshing it Feb 18 15:53:59 red-volumio volumio[12498]: info: MyVolumio login type: Token Feb 18 15:54:00 red-volumio volumio[12498]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 18 15:54:01 red-volumio volumio[12498]: info: MyVolumio token set successfully Feb 18 15:54:01 red-volumio volumio[12498]: info: MYVOLUMIO: Adding device Feb 18 15:54:01 red-volumio volumio[12498]: info: MYVOLUMIO: Evaluating Server Feb 18 15:54:01 red-volumio volumio[12498]: info: MyVolumio Plan changed: premium Feb 18 15:54:01 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 18 15:54:01 red-volumio volumio[12498]: info: Removing browser output: myVolumio user plan is not superstar Feb 18 15:54:01 red-volumio volumio[12498]: info: Removing audio output: Feb 18 15:54:01 red-volumio volumio[12498]: info: MYVOLUMIO: Adding device Feb 18 15:54:01 red-volumio volumio[12498]: info: MYVOLUMIO: Evaluating Server Feb 18 15:54:01 red-volumio volumio[12498]: info: Remote config written successfully Feb 18 15:54:01 red-volumio volumio[12498]: info: Starting Tunnel 1 Feb 18 15:54:01 red-volumio volumio[12498]: info: Starting Tunnel Connection Checker Feb 18 15:54:01 red-volumio volumio[12498]: info: Completed starting MyVolumio Plugin Feb 18 15:54:01 red-volumio volumio[12498]: info: MYVolumio Device enabled Feb 18 15:54:01 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 18 15:54:01 red-volumio volumio[12498]: info: MyVolumio status changed Feb 18 15:54:01 red-volumio volumio[12498]: info: Streaming services startup Feb 18 15:54:01 red-volumio volumio[12498]: info: Starting Streaming Daemon Feb 18 15:54:01 red-volumio volumio[12498]: info: Setting Geolocation for MyVolumio to eu5 Feb 18 15:54:01 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:01 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:01 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:01 red-volumio sudo[12916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 15:54:01 red-volumio sudo[12916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:01 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 18 15:54:01 red-volumio sudo[12916]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:01 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 18 15:54:01 red-volumio volumio[12498]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 18 15:54:01 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 18 15:54:02 red-volumio sudo[12920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 18 15:54:02 red-volumio sudo[12920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:02 red-volumio sudo[12920]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:02 red-volumio volumio[12498]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 18 15:54:02 red-volumio volumio[12498]: info: MRS: MultiRoom plugin initialized Feb 18 15:54:02 red-volumio volumio[12498]: info: MRS: STOPPING SNAPCLIENT Feb 18 15:54:02 red-volumio volumio[12498]: info: MRS: Snap server stop Feb 18 15:54:02 red-volumio volumio[12498]: info: MRS: STOPPING volumioStreaming Feb 18 15:54:02 red-volumio sudo[12937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 18 15:54:02 red-volumio sudo[12937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:02 red-volumio sudo[12939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 18 15:54:02 red-volumio sudo[12939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 18 15:54:02 red-volumio sudo[12943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 18 15:54:02 red-volumio sudo[12943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:02 red-volumio sudo[12943]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:02 red-volumio sudo[12944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 18 15:54:02 red-volumio sudo[12944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 18 15:54:02 red-volumio sudo[12937]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 18 15:54:02 red-volumio sudo[12939]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:02 red-volumio sudo[12944]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 18 15:54:02 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 18 15:54:02 red-volumio volumio[12498]: info: Preparing to generate the ALSA configuration file Feb 18 15:54:02 red-volumio volumio[12498]: info: Updating MyVolumio device info Feb 18 15:54:02 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:02 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:02 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:02 red-volumio volumio[12498]: info: Setting Geolocation for MyVolumio to eu8 Feb 18 15:54:02 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:02 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:02 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:02 red-volumio volumio[12498]: info: Successfully Added MyVolumio device Feb 18 15:54:03 red-volumio volumio[12498]: info: MRS: Removed streaming files Feb 18 15:54:03 red-volumio volumio[12498]: info: MRS: volumioStreaming STOPPED Feb 18 15:54:03 red-volumio volumio[12498]: info: MRS: SNAPSERVER STOPPED Feb 18 15:54:03 red-volumio volumio[12498]: info: MRS: SNAPCLIENT STOPPED Feb 18 15:54:03 red-volumio volumio[12498]: error: Cannot start Volumio Streaming Daemon Feb 18 15:54:03 red-volumio volumio[12498]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 15:54:03 red-volumio volumio[12498]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 15:54:03 red-volumio volumio[12498]: info: Asound.conf file unchanged, so no further update is needed Feb 18 15:54:03 red-volumio volumio[12498]: info: Output device has changed, restarting MPD Feb 18 15:54:03 red-volumio volumio[12498]: info: Output device has changed, restarting Shairport Sync Feb 18 15:54:03 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:03 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:03 red-volumio sudo[12949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:54:03 red-volumio sudo[12949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:03 red-volumio sudo[12949]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:03 red-volumio sudo[12951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:54:03 red-volumio sudo[12951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:03 red-volumio volumio[12498]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:54:03 red-volumio volumio[12498]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 18 15:54:03 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding METAVOLUMIO REST API Endpoints Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 18 15:54:03 red-volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 18 15:54:03 red-volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 18 15:54:03 red-volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 18 15:54:03 red-volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 18 15:54:03 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 18 15:54:03 red-volumio volumio[12498]: info: Preparing CD Folders Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding CD REST API Endpoints Feb 18 15:54:03 red-volumio volumio[12498]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 18 15:54:03 red-volumio volumio[12498]: info: Starting UDEV Watcher for CD Feb 18 15:54:03 red-volumio volumio[12498]: info: Detecting CD presence with UDEV Feb 18 15:54:03 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 18 15:54:03 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 15:54:03 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 15:54:03 red-volumio systemd[1]: mpd.service: Consumed 2.048s CPU time. Feb 18 15:54:03 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 15:54:03 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 15:54:03 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 15:54:03 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 15:54:03 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 15:54:03 red-volumio sudo[12964]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:54:03 red-volumio sudo[12964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 15:54:03 red-volumio sudo[12964]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:04 red-volumio mpd[12966]: 2026-02-18T15:54:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 15:54:04 red-volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 15:54:04 red-volumio sudo[12951]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:04 red-volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 18 15:54:05 red-volumio bash[12997]: cset: --> shielding system active with Feb 18 15:54:05 red-volumio bash[12997]: cset: "system" cpuset of CPUSPEC(1-3) with 140 tasks running Feb 18 15:54:05 red-volumio bash[12997]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 18 15:54:05 red-volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 18 15:54:08 red-volumio volumio[12498]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 18 15:54:08 red-volumio volumio[12498]: error: Hi Res Audio Failed Login: Missing Login Data Feb 18 15:54:08 red-volumio volumio[12498]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 18 15:54:08 red-volumio volumio[12498]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 18 15:54:08 red-volumio volumio[12498]: info: Refreshing QOBUZ token Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Feb 18 15:54:08 red-volumio volumio[12498]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 18 15:54:08 red-volumio volumio[12498]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 18 15:54:08 red-volumio volumio[12498]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 18 15:54:08 red-volumio volumio[12498]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 18 15:54:08 red-volumio volumio[12498]: info: Adding TIDAL REST API Endpoints Feb 18 15:54:08 red-volumio volumio[12498]: info: Updating MyVolumio device info Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:08 red-volumio sudo[13003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 18 15:54:08 red-volumio sudo[13003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio volumio[12498]: info: Successfully Updated MyVolumio device Feb 18 15:54:08 red-volumio volumio[12498]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 18 15:54:08 red-volumio volumio[12498]: info: MPD Permissions set Feb 18 15:54:08 red-volumio sudo[13003]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio sudo[13006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 18 15:54:08 red-volumio sudo[13006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio volumio[12498]: error: updateQueue error: null Feb 18 15:54:08 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1390. Feb 18 15:54:08 red-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 18 15:54:08 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:08 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:54:08 red-volumio qobuz-connect[4410]: 20260218 15:54:08.337 [4410.4410] INFO SampleApp: Stopping Local configuration server Feb 18 15:54:08 red-volumio volumio[12498]: info: Preparing to generate the ALSA configuration file Feb 18 15:54:08 red-volumio sudo[13020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:54:08 red-volumio sudo[13020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio sudo[13022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 18 15:54:08 red-volumio sudo[13022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:08 red-volumio volumio[12498]: info: Asound.conf file unchanged, so no further update is needed Feb 18 15:54:08 red-volumio volumio[12498]: info: Output device has changed, restarting MPD Feb 18 15:54:08 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 15:54:08 red-volumio upmpdcli[13017]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:54:08 red-volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Feb 18 15:54:08 red-volumio autossh[1704]: received signal to exit (15) Feb 18 15:54:08 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 15:54:08 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:54:08 red-volumio systemd[1]: shairport-sync.service: Consumed 1.583s CPU time. Feb 18 15:54:08 red-volumio volumio[12498]: info: Output device has changed, restarting Shairport Sync Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:08 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:54:08 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:54:08 red-volumio systemd[1]: sshtunnel.service: Deactivated successfully. Feb 18 15:54:08 red-volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 18 15:54:08 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:54:08 red-volumio sudo[13020]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:08 red-volumio sudo[13027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:54:08 red-volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 18 15:54:08 red-volumio sudo[13027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio sudo[13027]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:08 red-volumio autossh[13035]: port set to 0, monitoring disabled Feb 18 15:54:08 red-volumio autossh[13035]: starting ssh (count 1) Feb 18 15:54:08 red-volumio autossh[13035]: ssh child pid is 13039 Feb 18 15:54:08 red-volumio sudo[13028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:54:08 red-volumio sudo[13028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio volumio[12498]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:54:08 red-volumio volumio[12498]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio sudo[13022]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:08 red-volumio volumio[12498]: info: QobuzConnect: setDeactiveState invoked Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:08 red-volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Feb 18 15:54:08 red-volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 18 15:54:08 red-volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 18 15:54:08 red-volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 15:54:08 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 15:54:08 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 15:54:08 red-volumio systemd[1]: mpd.service: Consumed 1.563s CPU time. Feb 18 15:54:08 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 15:54:08 red-volumio sudo[13060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 18 15:54:08 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 15:54:08 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 15:54:08 red-volumio sudo[13060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio volumio[12498]: info: Successfully Updated MyVolumio device Feb 18 15:54:08 red-volumio volumio[12498]: info: Successfully Added MyVolumio device Feb 18 15:54:08 red-volumio volumio[12498]: info: MPD Permissions set Feb 18 15:54:08 red-volumio volumio[12498]: info: Remote SSH Started Feb 18 15:54:08 red-volumio volumiossh-tunnel[13039]: Warning: Permanently added '[eu8.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 18 15:54:08 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 15:54:08 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 15:54:08 red-volumio sudo[13060]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:08 red-volumio sudo[13066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 18 15:54:08 red-volumio sudo[13066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:08 red-volumio sudo[13065]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:54:08 red-volumio sudo[13065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 15:54:08 red-volumio sudo[13065]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:08 red-volumio volumio[12498]: info: Shairport-Sync Started Feb 18 15:54:08 red-volumio volumio[12498]: info: Access Token successfully retrieved Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:54:08 red-volumio volumio[12498]: info: [1771426448908] CoreMusicLibrary::Adding element QOBUZ Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:54:08 red-volumio volumio[12498]: Cannot find translation for source Radio Paradise Feb 18 15:54:08 red-volumio volumio[12498]: Cannot find translation for source QOBUZ Feb 18 15:54:08 red-volumio volumio[12498]: info: Stopping AccessToken refresher cron for QOBUZ Feb 18 15:54:08 red-volumio volumio[12498]: info: AccessToken refresher cron started for QOBUZ Feb 18 15:54:08 red-volumio volumio[12498]: info: Adding QOBUZ REST API Endpoints Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:08 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 18 15:54:09 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:54:09 red-volumio sudo[13076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:54:09 red-volumio volumio[12498]: info: Preparing to generate the ALSA configuration file Feb 18 15:54:09 red-volumio sudo[13076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:09 red-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 18 15:54:09 red-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 18 15:54:09 red-volumio systemd[1]: qobuz-connect.service: Consumed 2min 31.299s CPU time. Feb 18 15:54:09 red-volumio volumio[12498]: info: Asound.conf file unchanged, so no further update is needed Feb 18 15:54:09 red-volumio volumio[12498]: info: Output device has changed, restarting MPD Feb 18 15:54:09 red-volumio sudo[13081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:54:09 red-volumio volumio[12498]: info: Output device has changed, restarting Shairport Sync Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:09 red-volumio sudo[13081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:09 red-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 18 15:54:09 red-volumio sudo[13066]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio sudo[13006]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 15:54:09 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 15:54:09 red-volumio sudo[13081]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:54:09 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:54:09 red-volumio sudo[13076]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio sudo[13082]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:54:09 red-volumio volumio[12498]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:54:09 red-volumio volumio[12498]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio sudo[13082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:09 red-volumio volumio[12498]: info: QobuzConnect: setDeactiveState invoked Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:09 red-volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 15:54:09 red-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 15:54:09 red-volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 15:54:09 red-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 15:54:09 red-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:09 red-volumio volumio[12498]: info: MPD Permissions set Feb 18 15:54:09 red-volumio volumio[12498]: info: Shairport-Sync Started Feb 18 15:54:09 red-volumio volumio[12498]: [Metrics] CommandRouter: 27s 52.96ms Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::volumiosetStartupVolume Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:09 red-volumio sudo[13112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 18 15:54:09 red-volumio sudo[13112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::Close All Modals sent Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::Close All Modals sent Feb 18 15:54:09 red-volumio volumio[12498]: info: Executing endpoint qc_getconfig Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 18 15:54:09 red-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 15:54:09 red-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.441 [13078.13078] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.448 [13078.13078] INFO VolumeManager: [0xded408]: Setting new playback volume: 75 Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.449 [13078.13078] INFO VolumeManager: [0xded408]: Setting new mute state: 0 Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.450 [13078.13078] INFO QobuzConnect: [0xdeddd8]: Client initialized! Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.450 [13078.13078] INFO SampleApp: Starting Avahi advertising, name: Red Volumio, service name: _qobuz-connect._tcp Feb 18 15:54:09 red-volumio sudo[13112]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.475 [13078.13078] INFO LocalConfigManager: [0xdecce8]: Starting Local Configuration server Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.476 [13078.13078] INFO SampleApp: Starting Local configuration server Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.476 [13078.13078] INFO SampleApp: Connected to UNIX socket client 0xdd7818 Feb 18 15:54:09 red-volumio volumio[12498]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 18 15:54:09 red-volumio volumio[12498]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 18 15:54:09 red-volumio sudo[13121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 18 15:54:09 red-volumio sudo[13121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:09 red-volumio volumio[12498]: info: Starting Shairport Sync Feb 18 15:54:09 red-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 18 15:54:09 red-volumio qobuz-connect[13078]: 20260218 15:54:09.575 [13078.13078] INFO SampleApp: Stopping Local configuration server Feb 18 15:54:09 red-volumio sudo[13131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:54:09 red-volumio sudo[13131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 15:54:09 red-volumio sudo[13119]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:54:09 red-volumio sudo[13119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 15:54:09 red-volumio sudo[13119]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 15:54:09 red-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 15:54:09 red-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:54:09 red-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 15:54:09 red-volumio sudo[13131]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:09 red-volumio volumio[12498]: info: Shairport-Sync Started Feb 18 15:54:10 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 18 15:54:10 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 18 15:54:10 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 18 15:54:11 red-volumio mpd[13134]: 2026-02-18T15:54:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 15:54:11 red-volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 15:54:11 red-volumio sudo[13082]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:11 red-volumio sudo[13028]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:11 red-volumio volumio[12498]: error: MPD error: The expression evaluated to a falsy value: Feb 18 15:54:11 red-volumio volumio[12498]: assert.ok(self.idling) Feb 18 15:54:11 red-volumio volumio[12498]: error: The expression evaluated to a falsy value: Feb 18 15:54:11 red-volumio volumio[12498]: assert.ok(self.idling) Feb 18 15:54:11 red-volumio volumio[12498]: error: updateQueue error: null Feb 18 15:54:11 red-volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 18 15:54:11 red-volumio qobuz-connect[13078]: 20260218 15:54:11.484 [13078.13078] INFO SampleApp: shat down connection on UNIX socket Feb 18 15:54:11 red-volumio volumio[12498]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 18 15:54:11 red-volumio volumio[12498]: info: QobuzConnect: setDeactiveState invoked Feb 18 15:54:11 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:11 red-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 18 15:54:11 red-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 18 15:54:11 red-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 18 15:54:11 red-volumio sudo[13121]: pam_unix(sudo:session): session closed for user root Feb 18 15:54:11 red-volumio volumio[12498]: info: Executing endpoint qc_getconfig Feb 18 15:54:11 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.652 [13164.13164] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 18 15:54:11 red-volumio volumio[12498]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 18 15:54:11 red-volumio volumio[12498]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.661 [13164.13164] INFO VolumeManager: [0x1249408]: Setting new playback volume: 75 Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.661 [13164.13164] INFO VolumeManager: [0x1249408]: Setting new mute state: 0 Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.662 [13164.13164] INFO QobuzConnect: [0x1249dd8]: Client initialized! Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.662 [13164.13164] INFO SampleApp: Starting Avahi advertising, name: Red Volumio, service name: _qobuz-connect._tcp Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.675 [13164.13164] INFO LocalConfigManager: [0x1248ce8]: Starting Local Configuration server Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.676 [13164.13164] INFO SampleApp: Starting Local configuration server Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.677 [13164.13164] INFO SampleApp: Connected to UNIX socket client 0x1233818 Feb 18 15:54:11 red-volumio qobuz-connect[13164]: 20260218 15:54:11.804 [13164.13164] INFO SampleApp: Playback volume changed: 75 Feb 18 15:54:11 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:12 red-volumio bash[13175]: cset: --> shielding system active with Feb 18 15:54:12 red-volumio bash[13175]: cset: "system" cpuset of CPUSPEC(1-3) with 141 tasks running Feb 18 15:54:12 red-volumio bash[13175]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 18 15:54:12 red-volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 18 15:54:13 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 15:54:13 red-volumio volumio[12498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 18 15:54:13 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:16 red-volumio volumio[12498]: info: BOOT COMPLETED Feb 18 15:54:16 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:54:16 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:54:16 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 15:54:16 red-volumio volumio-remote-updater[784]: Test mode disabled Feb 18 15:54:16 red-volumio volumio-remote-updater[784]: Alpha mode disabled Feb 18 15:54:16 red-volumio volumio-remote-updater[784]: Alpha legacy test mode disabled Feb 18 15:54:16 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 18 15:54:16 red-volumio volumio[12498]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 18 15:54:16 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetBrowseSources Feb 18 15:54:18 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:54:19 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 15:54:19 red-volumio volumio[12498]: info: CURURI: artists:// Feb 18 15:54:19 red-volumio volumio[12498]: info: Preload queue cleared Feb 18 15:54:23 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1391. Feb 18 15:54:23 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:23 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:23 red-volumio upmpdcli[13195]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:54:23 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:54:23 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:54:33 red-volumio volumio[12498]: info: Preload queue cleared Feb 18 15:54:36 red-volumio dhcpcd[829]: eth0: failed to renew DHCP, rebinding Feb 18 15:54:36 red-volumio dhcpcd[829]: eth0: leased 192.168.1.177 for 180 seconds Feb 18 15:54:37 red-volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 18 15:54:37 red-volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 18 15:54:37 red-volumio systemd[1]: welcome.service: Deactivated successfully. Feb 18 15:54:37 red-volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 18 15:54:37 red-volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 18 15:54:37 red-volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 18 15:54:37 red-volumio welcome[13241]: Resolved ip:[1] 192.168.1.177 Feb 18 15:54:37 red-volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 18 15:54:37 red-volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 18 15:54:37 red-volumio volumio5-onboarding[1296]: time=2026-02-18T15:54:37.483+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.55:64428 @ 0x1400480" available=true connected=true macAddress=e4:5f:01:e2:a6:d3 ip4Address=192.168.1.177/24 ip6Address= Feb 18 15:54:37 red-volumio volumio[12498]: info: Received Get System Info Feb 18 15:54:37 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:54:37 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:54:37 red-volumio volumio[12498]: info: Discovery: Getting this device information Feb 18 15:54:37 red-volumio volumio[12498]: info: CoreCommandRouter::volumioGetState Feb 18 15:54:37 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:54:37 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:54:37 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:54:38 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1392. Feb 18 15:54:38 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:38 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:38 red-volumio upmpdcli[13249]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:54:38 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:54:38 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:54:39 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 15:54:39 red-volumio volumio[12498]: info: CURURI: playlists Feb 18 15:54:39 red-volumio volumio[12498]: info: Listing playlists Feb 18 15:54:39 red-volumio volumio[12498]: info: Preload queue cleared Feb 18 15:54:53 red-volumio volumio[12498]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Feb 18 15:54:53 red-volumio volumio[12498]: info: Preload queue cleared Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: https://stream.radioparadise.com/flacm Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: qobuz://song/380477595 Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: qobuz://song/69974835 Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: qobuz://song/362771453 Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: qobuz://song/1942121 Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: qobuz://song/3147371 Feb 18 15:54:53 red-volumio volumio[12498]: info: Preloading song: qobuz://song/6005878 Feb 18 15:54:53 red-volumio volumio[12498]: info: Exploding uri https://stream.radioparadise.com/flacm in service radio_paradise Feb 18 15:54:53 red-volumio volumio[12498]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/flacm" Feb 18 15:54:53 red-volumio volumio[12498]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:54:53 red-volumio volumio[12498]: TypeError: Cannot read properties of undefined (reading 'then') Feb 18 15:54:53 red-volumio volumio[12498]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Feb 18 15:54:53 red-volumio volumio[12498]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Feb 18 15:54:53 red-volumio volumio[12498]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Feb 18 15:54:53 red-volumio volumio[12498]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Feb 18 15:54:53 red-volumio volumio[12498]: at listOnTimeout (node:internal/timers:573:17) Feb 18 15:54:53 red-volumio volumio[12498]: at process.processTimers (node:internal/timers:514:7) Feb 18 15:54:53 red-volumio volumio[12498]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:54:53 red-volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1393. Feb 18 15:54:53 red-volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:54 red-volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 15:54:54 red-volumio upmpdcli[13291]: Could not open config: /tmp/upmpdcli.conf Feb 18 15:54:54 red-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:54:54 red-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 15:54:54 red-volumio sudo[13294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 15:53' Feb 18 15:54:54 red-volumio sudo[13294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"