Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioNext Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::next Jun 01 20:19:20 volumio volumio[1249]: info: [1780334360071] ControllerTidal::next Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::next Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::stop Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::stPlaybackTimer Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 50 Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 50 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::serviceStop Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 50 Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::serviceStop Jun 01 20:19:20 volumio volumio[1249]: info: [1780334360073] ControllerTidal::stop Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::stop Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.074+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.074+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023303 title=Windowlicker Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand stop took 13 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::play index undefined Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::startPlaybackTimer Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: [1780334360086] ControllerTidal::clearAddPlayTrack Jun 01 20:19:20 volumio volumio[1249]: info: Getting stream with soundQuality HI_RES Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 5 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 4 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 3 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.095+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.096+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.096+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.096+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 10ms Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 9ms Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.099+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.099+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 11ms Jun 01 20:19:20 volumio volumio[1249]: info: getStreamUrl took 251 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand stop took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:20 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand clear took 0 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==" Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:20 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:20 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:20 volumio volumio[1249]: error: updateQueue error: null Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:20 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==" took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 0ms Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand play Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:20 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:20 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 2ms Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand play took 2 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 4 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 5 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 5 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: info: Jun 01 20:19:20 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 2 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"743 Kbps","isStreaming":false,"title":"0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","trackType":"tidal"} Jun 01 20:19:20 volumio volumio[1249]: verbose: CURRENT POSITION 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus stop Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 6ms Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand status took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1169,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"743 Kbps","isStreaming":false,"title":"0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","trackType":"tidal"} Jun 01 20:19:20 volumio volumio[1249]: verbose: CURRENT POSITION 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:20 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"713 Kbps","isStreaming":false,"title":"0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","trackType":"tidal"} Jun 01 20:19:20 volumio volumio[1249]: verbose: CURRENT POSITION 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:20 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"768 Kbps","isStreaming":false,"title":"0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","trackType":"tidal"} Jun 01 20:19:20 volumio volumio[1249]: verbose: CURRENT POSITION 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:20 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.496+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.496+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.496+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.496+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.496+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.497+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.497+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.497+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.497+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.497+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.497+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.498+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 18ms Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 18ms Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 15ms Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 01 20:19:20 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"768 Kbps","isStreaming":false,"title":"0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","trackType":"tidal"} Jun 01 20:19:20 volumio volumio[1249]: verbose: CURRENT POSITION 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:20 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:20 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:20 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"768 Kbps","isStreaming":false,"title":"0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2Y2Q4Yzg0NWJiMzcyODkyMDUxM2Y0NjAwYWRiNDQ4Zl82MS5tcDQ/0.flac?token=1780337960~ZmI4OWU4MjM1OTY3Nzg4MDFkZDJjZTRjMmM4NDIyZDgxZDVlZGRkMA==","trackType":"tidal"} Jun 01 20:19:20 volumio volumio[1249]: verbose: CURRENT POSITION 51 Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:20 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:20 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:20 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.507+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=31 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.507+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=31 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.507+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=31 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.507+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.507+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.507+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.508+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=31 volume=100 Jun 01 20:19:20 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:20.508+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 22ms Jun 01 20:19:20 volumio volumio[1249]: info: ------------------------------ 22ms Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioNext Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::next Jun 01 20:19:23 volumio volumio[1249]: info: [1780334363875] ControllerTidal::next Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::next Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::stop Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::stPlaybackTimer Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::serviceStop Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 51 Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::serviceStop Jun 01 20:19:23 volumio volumio[1249]: info: [1780334363877] ControllerTidal::stop Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::stop Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.877+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.877+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/16023099 title="Come On My Selector" Jun 01 20:19:23 volumio volumio[1249]: info: Jun 01 20:19:23 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand stop took 8 milliseconds Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::play index undefined Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::startPlaybackTimer Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: [1780334363885] ControllerTidal::clearAddPlayTrack Jun 01 20:19:23 volumio volumio[1249]: info: Getting stream with soundQuality HI_RES Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand status took 2 milliseconds Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:23 volumio volumio[1249]: info: Jun 01 20:19:23 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:23 volumio volumio[1249]: info: Jun 01 20:19:23 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand status took 0 milliseconds Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand status took 1 milliseconds Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.890+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.890+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:23 volumio volumio[1249]: info: ------------------------------ 6ms Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 20:19:23 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:23 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 01 20:19:23 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:23 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:23 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:23 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:23 volumio volumio[1249]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.893+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=6 volume=100 Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.894+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.894+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_STOPPED positionMs=6 volume=100 Jun 01 20:19:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:23.894+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:23 volumio volumio[1249]: info: ------------------------------ 8ms Jun 01 20:19:23 volumio volumio[1249]: info: ------------------------------ 8ms Jun 01 20:19:24 volumio volumio[1249]: info: getStreamUrl took 210 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand stop took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand clear took 0 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==" Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:24 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:24 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:24 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:24 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==" took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 0ms Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 0ms Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand play Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:24 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Jun 01 20:19:24 volumio volumio[1249]: info: Ignoring MPD Status Update Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand play took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 1ms Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 0ms Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand status took 5 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand status took 4 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand status took 4 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:24 volumio volumio[1249]: info: Jun 01 20:19:24 volumio volumio[1249]: ---------------------------- MPD announces state update: player Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::getState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand status took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand status took 1 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:24 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:24 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","trackType":"tidal"} Jun 01 20:19:24 volumio volumio[1249]: verbose: CURRENT POSITION 52 Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus stop Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:24 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:24 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","trackType":"tidal"} Jun 01 20:19:24 volumio volumio[1249]: verbose: CURRENT POSITION 52 Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:24 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:24 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:24 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","trackType":"tidal"} Jun 01 20:19:24 volumio volumio[1249]: verbose: CURRENT POSITION 52 Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:24 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 11ms Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.142+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.143+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.144+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.144+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.144+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.144+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.145+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.145+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 13ms Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 13ms Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand status took 9 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseState Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:24 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:24 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","trackType":"tidal"} Jun 01 20:19:24 volumio volumio[1249]: verbose: CURRENT POSITION 52 Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:24 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:24 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:24 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"822 Kbps","isStreaming":false,"title":"0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","trackType":"tidal"} Jun 01 20:19:24 volumio volumio[1249]: verbose: CURRENT POSITION 52 Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:24 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.152+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=11 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.152+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.152+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=11 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.152+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.153+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=11 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.153+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.153+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=11 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.154+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 19ms Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 18ms Jun 01 20:19:24 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 01 20:19:24 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Jun 01 20:19:24 volumio volumio[1249]: info: ControllerMpd::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:19:24 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:19:24 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"758 Kbps","isStreaming":false,"title":"0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczZmMzMjg5NzkxMmFhZGZjYTRhZTNmNTliNmNmZjY1Y182MS5tcDQ/0.flac?token=1780337964~MjRkZjI4NWE4OGQxZDA5YTRmOWY4ZTY3YWIyZWRkNmRhOWI3YTNiZQ==","trackType":"tidal"} Jun 01 20:19:24 volumio volumio[1249]: verbose: CURRENT POSITION 52 Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Jun 01 20:19:24 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:19:24 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:19:24 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.168+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=30 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.168+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.168+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=30 volume=100 Jun 01 20:19:24 volumio volumio5-onboarding[1429]: time=2026-06-01T20:19:24.169+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:19:24 volumio volumio[1249]: info: ------------------------------ 32ms Jun 01 20:19:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 20:19:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 01 20:19:56 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 01 20:20:03 volumio volumio[1249]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/fusiondsp/3.0.5/volumio/bookworm/armhf Jun 01 20:20:03 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:03.458+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:05 volumio volumio[1249]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/fusiondsp/3.0.5/volumio/bookworm/armhf Jun 01 20:20:05 volumio volumio[1249]: info: Folder /tmp/plugins removed Jun 01 20:20:05 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:05.276+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:05 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:05.419+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:05 volumio volumio[1249]: info: Check plugin dependencies Jun 01 20:20:05 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 20:20:05 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:05.435+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:05 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:05.436+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:05 volumio volumio[1249]: info: Checking if plugin already exists Jun 01 20:20:05 volumio volumio[1249]: info: An error occurred installing the plugin. Rolling back config Jun 01 20:20:05 volumio volumio[1249]: info: Plugin folders cleanup Jun 01 20:20:05 volumio volumio[1249]: info: Scanning into folder /volumio/app/plugins/ Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category audio_interface Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category miscellanea Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category music_service Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category plugins.json Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category system_controller Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category user_interface Jun 01 20:20:05 volumio volumio[1249]: info: Scanning into folder /data/plugins/ Jun 01 20:20:05 volumio volumio[1249]: info: Scanning category audio_interface Jun 01 20:20:05 volumio volumio[1249]: info: Plugin folders cleanup completed Jun 01 20:20:05 volumio volumio[1249]: info: Error: Error Jun 01 20:20:05 volumio volumio[1249]: info: Folder /tmp/plugins removed Jun 01 20:20:05 volumio volumio[1249]: info: Folder /tmp/downloaded_plugin.zip removed Jun 01 20:20:05 volumio volumio[1249]: info: Folder /tmp/downloaded_plugin.zip removed Jun 01 20:20:05 volumio volumio[1249]: info: Folder /data/temp removed Jun 01 20:20:15 volumio volumio[1249]: info: Starting Uninstall of plugin audio_interface - fusiondsp Jun 01 20:20:15 volumio volumio[1249]: info: Uninstalling plugin fusiondsp Jun 01 20:20:15 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:15.266+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:15 volumio volumio[1249]: info: Disabling plugin fusiondsp Jun 01 20:20:15 volumio volumio[1249]: info: Preparing to generate the ALSA configuration file Jun 01 20:20:15 volumio volumio[1249]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 01 20:20:15 volumio volumio[1249]: info: Reading ALSA contributions from plugins. Jun 01 20:20:15 volumio volumio[1249]: info: Asound.conf file unchanged, so no further update is needed Jun 01 20:20:15 volumio volumio[1249]: info: Output device has changed, restarting MPD Jun 01 20:20:15 volumio volumio[1249]: info: Output device has changed, restarting Shairport Sync Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:15 volumio sudo[15590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 20:20:15 volumio sudo[15590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 20:20:15 volumio sudo[15590]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio sudo[15592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 20:20:15 volumio volumio[1249]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 20:20:15 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:15 volumio vtcs[13929]: [2026-06-01 20:20:15.310] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Jun 01 20:20:15 volumio vtcs[13929]: [2026-06-01 20:20:15.310] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 01 20:20:15 volumio volumio[1249]: info: Checking if uninstall.sh is present Jun 01 20:20:15 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:15.314+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:15 volumio sudo[15602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:15 volumio sudo[15602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:15 volumio sudo[15606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 01 20:20:15 volumio volumio[1249]: info: Executing uninstall.sh Jun 01 20:20:15 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 20:20:15 volumio volumio[1249]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jun 01 20:20:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 20:20:15 volumio systemd[1]: mpd.service: Consumed 21.628s CPU time. Jun 01 20:20:15 volumio systemd[1]: vtcs.service: Deactivated successfully. Jun 01 20:20:15 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 01 20:20:15 volumio volumio[1249]: info: MPD Permissions set Jun 01 20:20:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 20:20:15 volumio sudo[15614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 01 20:20:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 20:20:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 20:20:15 volumio sudo[15614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 20:20:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio sudo[15618]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Jun 01 20:20:15 volumio sudo[15618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: Starting Shairport Sync Jun 01 20:20:15 volumio sudo[15629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 20:20:15 volumio sudo[15629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15602]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: info: Preparing to generate the ALSA configuration file Jun 01 20:20:15 volumio sudo[15614]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio sudo[15634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 01 20:20:15 volumio sudo[15634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15606]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 01 20:20:15 volumio volumio[1249]: info: Reading ALSA contributions from plugins. Jun 01 20:20:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 20:20:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 20:20:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:15 volumio systemd[1]: shairport-sync.service: Consumed 2.441s CPU time. Jun 01 20:20:15 volumio volumio[1249]: info: Asound.conf file written Jun 01 20:20:15 volumio sudo[15626]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 20:20:15 volumio sudo[15626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:15 volumio sudo[15626]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio sudo[15639]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm /etc/systemd/system/fusiondsp.service Jun 01 20:20:15 volumio sudo[15639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:15 volumio sudo[15639]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio sudo[15641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 20:20:15 volumio sudo[15641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15644]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Jun 01 20:20:15 volumio sudo[15644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:15 volumio sudo[15641]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 01 20:20:15 volumio volumio[1249]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 01 20:20:15 volumio volumio[1249]: info: Output device has changed, restarting MPD Jun 01 20:20:15 volumio volumio[1249]: info: Output device has changed, restarting Shairport Sync Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:15 volumio sudo[15651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 20:20:15 volumio sudo[15651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 20:20:15 volumio sudo[15653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15651]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 20:20:15 volumio volumio[1249]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:15 volumio sudo[15629]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio sudo[15666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:15 volumio systemd[1]: Reloading. Jun 01 20:20:15 volumio sudo[15666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:15 volumio sudo[15670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: MPD Permissions set Jun 01 20:20:15 volumio volumio[1249]: info: Shairport-Sync Started Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio sudo[15676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 01 20:20:15 volumio sudo[15676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: Starting Shairport Sync Jun 01 20:20:15 volumio volumio[1249]: info: Preparing to generate the ALSA configuration file Jun 01 20:20:15 volumio sudo[15699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 20:20:15 volumio sudo[15699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 01 20:20:15 volumio volumio[1249]: info: Reading ALSA contributions from plugins. Jun 01 20:20:15 volumio volumio[1249]: info: Asound.conf file written Jun 01 20:20:15 volumio sudo[15703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 20:20:15 volumio sudo[15703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15703]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 01 20:20:15 volumio volumio[1249]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 01 20:20:15 volumio volumio[1249]: info: Output device has changed, restarting MPD Jun 01 20:20:15 volumio sudo[15726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 20:20:15 volumio sudo[15726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15726]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: info: Output device has changed, restarting Shairport Sync Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 20:20:15 volumio sudo[15729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 20:20:15 volumio volumio[1249]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio sudo[15729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:15 volumio sudo[15738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:15 volumio sudo[15738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:15 volumio sudo[15740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio sudo[15748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 01 20:20:15 volumio sudo[15748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: MPD Permissions set Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:15 volumio volumio[1249]: info: Starting Shairport Sync Jun 01 20:20:15 volumio sudo[15756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 20:20:15 volumio sudo[15756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 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. Jun 01 20:20:15 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. Jun 01 20:20:15 volumio sudo[15644]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio sudo[15618]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio volumio[1249]: info: Uninstall script completed Jun 01 20:20:15 volumio volumio[1249]: info: Removing plugin fusiondsp from configuration Jun 01 20:20:15 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:15.938+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:15 volumio sudo[15676]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio mpd[15664]: 2026-06-01T20:20:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 20:20:15 volumio sudo[15766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 01 20:20:15 volumio sudo[15766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:15 volumio volumio[1249]: info: Successfully removed fusiondsp configuration files Jun 01 20:20:15 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:15.967+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:15 volumio volumio[1249]: info: Plugin folders cleanup Jun 01 20:20:15 volumio volumio[1249]: info: Scanning into folder /volumio/app/plugins/ Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category audio_interface Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category miscellanea Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category music_service Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category plugins.json Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category system_controller Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category user_interface Jun 01 20:20:15 volumio volumio[1249]: info: Scanning into folder /data/plugins/ Jun 01 20:20:15 volumio volumio[1249]: info: Scanning category audio_interface Jun 01 20:20:15 volumio volumio[1249]: info: Cleaning folder for fusiondsp Jun 01 20:20:15 volumio sudo[15748]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 20:20:15 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jun 01 20:20:15 volumio qobuz-connect[13911]: 20260601 20:20:15.991 [13911.13911] INFO SampleApp: Stopping Local configuration server Jun 01 20:20:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 20:20:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 20:20:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:16 volumio sudo[15768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 01 20:20:16 volumio sudo[15768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:16 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 20:20:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 20:20:16 volumio sudo[15740]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15670]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15738]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15666]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 20:20:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 20:20:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 20:20:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 20:20:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 20:20:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:16 volumio sudo[15756]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15699]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio volumio[1249]: info: Plugin folders cleanup completed Jun 01 20:20:16 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 20:20:16 volumio volumio[1249]: info: [] Jun 01 20:20:16 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 01 20:20:16 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:16.136+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:16 volumio volumio[1249]: info: Shairport-Sync Started Jun 01 20:20:16 volumio volumio[1249]: info: Shairport-Sync Started Jun 01 20:20:16 volumio sudo[15775]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 20:20:16 volumio sudo[15775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:16 volumio sudo[15775]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio qobuz-connect[13911]: 20260601 20:20:16.313 [13911.13911] INFO SampleApp: shat down connection on UNIX socket Jun 01 20:20:16 volumio volumio[1249]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 01 20:20:16 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:16 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:16 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jun 01 20:20:16 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jun 01 20:20:16 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jun 01 20:20:16 volumio sudo[15768]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15634]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15766]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio volumio[1249]: info: Executing endpoint qc_getconfig Jun 01 20:20:16 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.358 [15793.15793] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.360 [15793.15793] INFO VolumeManager: [0x156bf68]: Setting new playback volume: 75 Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.360 [15793.15793] INFO VolumeManager: [0x156bf68]: Setting new mute state: 0 Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.360 [15793.15793] INFO AudioStreamManager: [0x156bcc0]: Setting new audio download buffer size: 1048576 Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.360 [15793.15793] INFO QobuzConnect: [0x156c830]: Client initialized! Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.360 [15793.15793] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 01 20:20:16 volumio volumio[1249]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 01 20:20:16 volumio volumio[1249]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.368 [15793.15793] INFO LocalConfigManager: [0x156b9e8]: Starting Local Configuration server Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.368 [15793.15793] INFO SampleApp: Starting Local configuration server Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.368 [15793.15793] INFO SampleApp: Connected to UNIX socket client 0x1556818 Jun 01 20:20:16 volumio qobuz-connect[15793]: 20260601 20:20:16.508 [15793.15793] INFO SampleApp: Playback volume changed: 75 Jun 01 20:20:16 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:16 volumio mpd[15792]: 2026-06-01T20:20:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 20:20:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 20:20:16 volumio sudo[15592]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15729]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio sudo[15653]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:16 volumio volumio[1249]: error: MPD error: The expression evaluated to a falsy value: Jun 01 20:20:16 volumio volumio[1249]: assert.ok(self.idling) Jun 01 20:20:16 volumio volumio[1249]: error: The expression evaluated to a falsy value: Jun 01 20:20:16 volumio volumio[1249]: assert.ok(self.idling) Jun 01 20:20:16 volumio volumio[1249]: error: MPD error: The expression evaluated to a falsy value: Jun 01 20:20:16 volumio volumio[1249]: assert.ok(self.idling) Jun 01 20:20:16 volumio volumio[1249]: error: The expression evaluated to a falsy value: Jun 01 20:20:16 volumio volumio[1249]: assert.ok(self.idling) Jun 01 20:20:16 volumio volumio[1249]: error: updateQueue error: null Jun 01 20:20:18 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:18 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:18 volumio volumio[1249]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:18 volumio volumio[1249]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:18 volumio sudo[15810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 01 20:20:18 volumio sudo[15810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:18 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 01 20:20:18 volumio sudo[15810]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:18 volumio volumio[1249]: info: Executing endpoint tc_getconfig Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 01 20:20:18 volumio vtcs[15813]: STARTING TidalConnect services, version: 1.6.1 Jun 01 20:20:18 volumio vtcs[15813]: STARTED TidalConnect services. Jun 01 20:20:18 volumio volumio[1249]: info: Executing endpoint tc_connect Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 01 20:20:18 volumio volumio[1249]: info: Connecting to TidalConnect Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:20:18 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:20:18 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:20:18 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:18 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:20:18 volumio volumio[1249]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:20:18 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:20:18 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:20:18 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:20:18 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:18 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:20:18 volumio volumio[1249]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 01 20:20:18 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:18.542+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=54256 volume=100 Jun 01 20:20:18 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:18.542+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=54256 volume=100 Jun 01 20:20:18 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:18.542+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:20:18 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:18.542+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:20:19 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:19 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:19 volumio volumio[1249]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:19 volumio volumio[1249]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:19 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:19 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:19 volumio sudo[15842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 01 20:20:19 volumio sudo[15842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:19 volumio volumio[1249]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:19 volumio volumio[1249]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:19 volumio sudo[15842]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:19 volumio sudo[15849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 01 20:20:19 volumio sudo[15849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:19 volumio sudo[15849]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:21 volumio volumio[1249]: info: TidalConnect service started! Jun 01 20:20:22 volumio volumio[1249]: info: TidalConnect service started! Jun 01 20:20:22 volumio volumio[1249]: info: TidalConnect service started! Jun 01 20:20:23 volumio volumio[1249]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/bookworm/armhf Jun 01 20:20:23 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:23.153+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:25 volumio volumio[1249]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/bookworm/armhf Jun 01 20:20:25 volumio volumio[1249]: info: Folder /tmp/plugins removed Jun 01 20:20:25 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:25.804+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:25 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:25.943+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:25 volumio volumio[1249]: info: Check plugin dependencies Jun 01 20:20:25 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 20:20:25 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:25.954+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:25 volumio volumio[1249]: info: Checking if plugin already exists Jun 01 20:20:25 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:25.955+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:25 volumio volumio[1249]: info: Rename folder Jun 01 20:20:25 volumio volumio[1249]: info: Folder /tmp/downloaded_plugin.zip removed Jun 01 20:20:25 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:25.961+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:25 volumio volumio[1249]: info: Move to category Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.032+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: Checking if install.sh is present Jun 01 20:20:26 volumio volumio[1249]: info: Executing install.sh Jun 01 20:20:26 volumio sudo[15886]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jun 01 20:20:26 volumio sudo[15886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:26 volumio volumio[1249]: info: creating filters folder and copying demo filters Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.047+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: copying demo flters Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.057+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/ Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.074+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/2XEQ15/ Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.075+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.077+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.078+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.078+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio sudo[15911]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Jun 01 20:20:26 volumio sudo[15911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/PEQ/ Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.090+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/convfir/ Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.099+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/convfir/kef.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/convfir/test.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/EQ15/ Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/EQ15/.Bass-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/EQ15/.Classic-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/EQ15/.Voice-FusionDsp.json Jun 01 20:20:26 volumio volumio[1249]: info: ./presets/EQ15/.Rock-FusionDsp.json Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.102+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.102+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.102+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.102+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.102+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.102+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.103+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.103+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: Installing/fusiondsp dependencies Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.118+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: Get:1 http://archive.raspberrypi.com/debian bookworm InRelease [55.0 kB] Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.357+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: Get:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease [15.0 kB] Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.395+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf Packages [14.5 MB] Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.614+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:26 volumio volumio[1249]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf Packages [569 kB] Jun 01 20:20:26 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:26.674+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:29 volumio volumio[1249]: info: Fetched 15.2 MB in 3s (5178 kB/s) Jun 01 20:20:29 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:29.077+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio volumio[1249]: info: Reading package lists... Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.203+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio volumio[1249]: info: Building dependency tree... Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.472+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio volumio[1249]: info: Reading state information... Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.478+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio volumio[1249]: info: 53 packages can be upgraded. Run 'apt list --upgradable' to see them. Jun 01 20:20:30 volumio sudo[15911]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.513+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio sudo[16063]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install --no-install-recommends python3-venv drc Jun 01 20:20:30 volumio sudo[16063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:30 volumio volumio[1249]: info: Reading package lists... Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.562+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio volumio[1249]: info: Building dependency tree... Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.807+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:30 volumio volumio[1249]: info: Reading state information... Jun 01 20:20:30 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:30.809+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:31 volumio volumio[1249]: info: python3-venv is already the newest version (3.11.2-1). Jun 01 20:20:31 volumio sudo[16063]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:31 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:31.316+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:31 volumio volumio[1249]: info: drc is already the newest version (3.2.3~dfsg0-1). Jun 01 20:20:31 volumio volumio[1249]: info: 0 upgraded, 0 newly installed, 0 to remove and 53 not upgraded. Jun 01 20:20:31 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:31.317+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:31 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:31.317+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:31 volumio volumio[1249]: info: creating sysytemd service Jun 01 20:20:31 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:31.320+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:31 volumio sudo[16071]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Jun 01 20:20:31 volumio sudo[16071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:31 volumio systemd[1]: Reloading. Jun 01 20:20:31 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. Jun 01 20:20:31 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. Jun 01 20:20:31 volumio sudo[16071]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:31 volumio volumio[1249]: info: copying hw detection script Jun 01 20:20:31 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:31.678+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:31 volumio volumio[1249]: info: Detected cpu architecture as aarch64 Jun 01 20:20:31 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:31.690+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:32 volumio sudo[16101]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Jun 01 20:20:32 volumio sudo[16101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:32 volumio sudo[16101]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:32 volumio sudo[16103]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jun 01 20:20:32 volumio sudo[16103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:32 volumio sudo[16103]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:32 volumio volumio[1249]: info: Downloading camillagui ... Please wait! Jun 01 20:20:32 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:32.594+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: Extracting camillagui ... Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.778+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/camillagui_backend Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.782+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.782+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.814+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.815+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft7/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft7/metaschema.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft3/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.817+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.817+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.817+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.817+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft3/metaschema.json Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.822+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/metaschema.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/core Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/content Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/meta-data Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/format-assertion Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/validation Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/applicator Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/format-annotation Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft202012/vocabularies/unevaluated Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft4/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft4/metaschema.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft6/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft6/metaschema.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/metaschema.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/core Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/content Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/meta-data Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/validation Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/applicator Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema_specifications/schemas/draft201909/vocabularies/format Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/libpython3.11.so.1.0 Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.825+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.826+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.826+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.826+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.826+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.827+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.827+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.827+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.828+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.828+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.830+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.830+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.830+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.831+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.831+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.831+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.831+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.831+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.831+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.865+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/fft/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.867+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/fft/_pocketfft_umath.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.868+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/_core/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.870+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/_core/_multiarray_tests.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.871+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/_core/_multiarray_umath.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.873+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.908+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/bit_generator.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.910+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_common.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.912+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_mt19937.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.914+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/mtrand.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.916+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_philox.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.919+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_generator.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.921+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_sfc64.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.927+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_bounded_integers.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.929+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/random/_pcg64.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.931+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/linalg/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.934+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/numpy/linalg/_umath_linalg.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/libstdc++.so.6 Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.935+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.935+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/yarl/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.946+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/yarl/_quoting_c.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.948+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/rpds/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.950+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/rpds/rpds.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.950+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/libssl.so.3 Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.956+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.961+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.963+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/resampler.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/basicfilters.json Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.965+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.966+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/dither.json Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.967+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/filter.json Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.969+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/compressor.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/race.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/pipeline.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/signalgen.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/sections.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/processor.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/mixer.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/playback.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/biquads.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/biquadcombo.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/diffeq.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/conv.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/capture.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/noisegate.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/camilladsp_plot/schemas/devices.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/libbz2.so.1.0 Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/libexpat.so.1 Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.974+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.974+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.974+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.974+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.975+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.975+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.975+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.975+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.976+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.976+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.977+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.977+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.977+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.978+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.978+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.978+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.978+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/ Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.992+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/css-variables.css Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/logo512.png Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/manifest.json Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/robots.txt Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/assets/ Jun 01 20:20:33 volumio volumio[1249]: info: camillagui_backend/_internal/build/assets/index-BcskH8eu.js Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.994+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.994+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.994+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.994+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.995+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:33 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:33.995+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/build/assets/camilladsp-BEmSmbVE.svg Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/build/assets/index-Baoof0XQ.css Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/build/index.html Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/build/logo192.png Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/build/favicon.ico Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.003+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libz.so.1 Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libcrypto.so.3 Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.005+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.006+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.006+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.006+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.006+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.006+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/base_library.zip Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.013+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/ Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.015+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_bz2.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.016+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.016+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_multibytecodec.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.020+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_codecs_hk.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.021+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_asyncio.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.025+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_codecs_kr.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/resource.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_codecs_iso2022.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_ssl.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.027+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.027+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.027+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.029+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_codecs_tw.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.033+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_codecs_cn.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/mmap.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_multiprocessing.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_codecs_jp.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.034+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.035+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.035+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.035+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_json.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_typing.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_hashlib.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_contextvars.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_decimal.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_queue.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_posixshmem.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_uuid.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_ctypes.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/termios.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/_lzma.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/python3.11/lib-dynload/readline.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/METADATA Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/entry_points.txt Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/INSTALLER Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/fft/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/fft/pocketfft/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/fft/pocketfft/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/include/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/include/numpy/ Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.043+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/include/numpy/libdivide/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/include/numpy/libdivide/LICENSE.txt Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/npysort/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/npysort/x86-simd-sort/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/npysort/x86-simd-sort/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/highway/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/highway/LICENSE Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/multiarray/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/multiarray/dragon4_LICENSE.txt Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/umath/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/umath/svml/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/umath/svml/LICENSE Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/common/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/common/pythoncapi-compat/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/_core/src/common/pythoncapi-compat/COPYING Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/ma/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/ma/LICENSE Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/distributions/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/distributions/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/mt19937/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/mt19937/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/philox/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/philox/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/pcg64/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/pcg64/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/sfc64/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/sfc64/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/splitmix64/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/random/src/splitmix64/LICENSE.md Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/linalg/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/linalg/lapack_lite/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/numpy/linalg/lapack_lite/LICENSE.txt Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/licenses/LICENSE.txt Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/WHEEL Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.055+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.056+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.056+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.056+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.056+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.057+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.057+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.057+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.058+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.058+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.058+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.059+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.059+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.060+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.060+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.060+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.064+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.064+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.064+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.064+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.065+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.065+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.065+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.065+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.065+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.070+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.070+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.070+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.070+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.070+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.071+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.071+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.071+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.071+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.072+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.072+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.072+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.072+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.074+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.074+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.074+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.074+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.078+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.078+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.078+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.080+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.080+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.080+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.080+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.080+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.080+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.081+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.081+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.081+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.081+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.081+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.083+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio sudo[15886]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/numpy-2.4.4.dist-info/RECORD Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.170+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: Install script completed Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.176+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: Adding reference to registry Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.178+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: Done installing plugin. Jun 01 20:20:34 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 20:20:34 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 01 20:20:34 volumio volumio[1249]: info: Folder /tmp/plugins removed Jun 01 20:20:34 volumio volumio[1249]: info: Folder /tmp/downloaded_plugin.zip removed Jun 01 20:20:34 volumio volumio[1249]: info: Folder /data/temp removed Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema/ Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.194+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema/benchmarks/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema/benchmarks/issue232/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema/benchmarks/issue232/issue.json Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libffi.so.8 Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/frozenlist/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/frozenlist/_frozenlist.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/aiohttp/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/aiohttp/_websocket/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/aiohttp/_websocket/reader_c.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.198+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.198+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.198+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.199+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.199+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.201+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.201+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.201+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.201+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/aiohttp/_websocket/mask.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.213+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/aiohttp/_http_parser.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/aiohttp/_http_writer.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libgcc_s.so.1 Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/REQUESTED Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/METADATA Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/entry_points.txt Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/INSTALLER Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/licenses/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/licenses/COPYING Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/WHEEL Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/jsonschema-4.26.0.dist-info/RECORD Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libreadline.so.8 Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/multidict/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/multidict/_multidict.cpython-311-arm-linux-gnueabihf.so Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/liblzma.so.5 Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/config/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/config/gui-config.yml Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/config/camillagui.yml Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libuuid.so.1 Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/METADATA Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/INSTALLER Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/licenses/ Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/licenses/LICENSE Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/WHEEL Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/RECORD Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/attrs-26.1.0.dist-info/RECORD Jun 01 20:20:34 volumio volumio[1249]: info: camillagui_backend/_internal/libtinfo.so.6 Jun 01 20:20:34 volumio volumio[1249]: Plugin install end detected on script Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.222+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.223+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.223+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.223+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.223+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.224+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.226+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.227+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.227+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.227+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.227+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.228+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.228+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.228+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.228+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.229+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.229+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.229+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.229+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.229+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.230+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.231+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.231+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.231+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.231+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.232+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.232+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:34 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:34.232+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 01 20:20:36 volumio volumio[1249]: info: Enabling plugin fusiondsp Jun 01 20:20:36 volumio volumio[1249]: info: Loading plugin "fusiondsp"... Jun 01 20:20:36 volumio volumio[1249]: info: Preparing to generate the ALSA configuration file Jun 01 20:20:36 volumio volumio[1249]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 01 20:20:36 volumio volumio[1249]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 01 20:20:36 volumio volumio[1249]: info: Reading ALSA contributions from plugins. Jun 01 20:20:36 volumio volumio[1249]: info: Asound.conf file written Jun 01 20:20:36 volumio sudo[16118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 20:20:36 volumio sudo[16118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio sudo[16118]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio volumio[1249]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 01 20:20:36 volumio volumio[1249]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 01 20:20:36 volumio volumio[1249]: info: Output device has changed, restarting MPD Jun 01 20:20:36 volumio volumio[1249]: info: Output device has changed, restarting Shairport Sync Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:36 volumio sudo[16124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 20:20:36 volumio sudo[16124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio sudo[16124]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio sudo[16126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 20:20:36 volumio sudo[16126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 20:20:36 volumio volumio[1249]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:36 volumio vtcs[15813]: [2026-06-01 20:20:36.829] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Jun 01 20:20:36 volumio vtcs[15813]: [2026-06-01 20:20:36.829] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 01 20:20:36 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 20:20:36 volumio volumio[1249]: info: PLUGIN START: fusiondsp Jun 01 20:20:36 volumio sudo[16136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:36 volumio sudo[16136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio volumio[1249]: info: FusionDsp - CamillaDSP binary found. Jun 01 20:20:36 volumio volumio[1249]: info: Loading i18n strings for locale ru Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: FusionDsp - mixtype--------------------- None Jun 01 20:20:36 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 20:20:36 volumio volumio[1249]: info: Preparing to generate the ALSA configuration file Jun 01 20:20:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 20:20:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 20:20:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 20:20:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 20:20:36 volumio volumio[1249]: info: Done. Jun 01 20:20:36 volumio sudo[16140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:36 volumio sudo[16140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 20:20:36 volumio volumio[1249]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 01 20:20:36 volumio volumio[1249]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 01 20:20:36 volumio volumio[1249]: info: Reading ALSA contributions from plugins. Jun 01 20:20:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 20:20:36 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 01 20:20:36 volumio systemd[1]: vtcs.service: Deactivated successfully. Jun 01 20:20:36 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 01 20:20:36 volumio sudo[16147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 01 20:20:36 volumio sudo[16147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio sudo[16136]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio volumio[1249]: info: MPD Permissions set Jun 01 20:20:36 volumio volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 01 20:20:36 volumio sudo[16147]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio sudo[16140]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio sudo[16151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 01 20:20:36 volumio sudo[16151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio sudo[16148]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 20:20:36 volumio sudo[16148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:36 volumio sudo[16148]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 20:20:36 volumio volumio[1249]: info: Starting Shairport Sync Jun 01 20:20:36 volumio volumio[1249]: info: Asound.conf file unchanged, so no further update is needed Jun 01 20:20:36 volumio volumio[1249]: info: Output device has changed, restarting MPD Jun 01 20:20:36 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jun 01 20:20:36 volumio qobuz-connect[15793]: 20260601 20:20:36.936 [15793.15793] INFO SampleApp: Stopping Local configuration server Jun 01 20:20:36 volumio sudo[16163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 20:20:36 volumio sudo[16163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio volumio[1249]: info: Output device has changed, restarting Shairport Sync Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:36 volumio sudo[16166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 20:20:36 volumio sudo[16166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio sudo[16168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 20:20:36 volumio sudo[16168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:36 volumio sudo[16166]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 20:20:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 20:20:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:36 volumio systemd[1]: shairport-sync.service: Consumed 1.536s CPU time. Jun 01 20:20:36 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 20:20:36 volumio volumio[1249]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:36 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 20:20:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 20:20:36 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:36 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:36 volumio sudo[16163]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 20:20:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 20:20:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 20:20:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 20:20:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 20:20:36 volumio sudo[16180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:36 volumio sudo[16180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:37 volumio sudo[16180]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio sudo[16197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 20:20:37 volumio sudo[16197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:37 volumio sudo[16206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 01 20:20:37 volumio sudo[16206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:37 volumio sudo[16197]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio sudo[16182]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 20:20:37 volumio sudo[16182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 20:20:37 volumio sudo[16182]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio sudo[16206]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio sudo[16210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 01 20:20:37 volumio sudo[16210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:37 volumio volumio[1249]: info: MPD Permissions set Jun 01 20:20:37 volumio volumio[1249]: info: Shairport-Sync Started Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 20:20:37 volumio volumio[1249]: info: Starting Shairport Sync Jun 01 20:20:37 volumio sudo[16220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 20:20:37 volumio sudo[16220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 20:20:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 20:20:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 20:20:37 volumio sudo[16220]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio volumio[1249]: info: Shairport-Sync Started Jun 01 20:20:37 volumio mpd[16211]: 2026-06-01T20:20:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 20:20:37 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 20:20:37 volumio sudo[16126]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio sudo[16168]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:37 volumio volumio[1249]: error: updateQueue error: null Jun 01 20:20:37 volumio volumio[1249]: error: updateQueue error: null Jun 01 20:20:38 volumio qobuz-connect[15793]: 20260601 20:20:38.393 [15793.15793] INFO SampleApp: shat down connection on UNIX socket Jun 01 20:20:38 volumio volumio[1249]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 01 20:20:38 volumio volumio[1249]: info: QobuzConnect: setDeactiveState invoked Jun 01 20:20:38 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:38 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jun 01 20:20:38 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jun 01 20:20:38 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jun 01 20:20:38 volumio sudo[16210]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:38 volumio sudo[16151]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:38 volumio volumio[1249]: info: Executing endpoint qc_getconfig Jun 01 20:20:38 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.442 [16239.16239] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 01 20:20:38 volumio volumio[1249]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 01 20:20:38 volumio volumio[1249]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.444 [16239.16239] INFO VolumeManager: [0x10faf68]: Setting new playback volume: 75 Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.444 [16239.16239] INFO VolumeManager: [0x10faf68]: Setting new mute state: 0 Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.444 [16239.16239] INFO AudioStreamManager: [0x10facc0]: Setting new audio download buffer size: 1048576 Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.444 [16239.16239] INFO QobuzConnect: [0x10fb830]: Client initialized! Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.444 [16239.16239] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.450 [16239.16239] INFO LocalConfigManager: [0x10fa9e8]: Starting Local Configuration server Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.450 [16239.16239] INFO SampleApp: Starting Local configuration server Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.451 [16239.16239] INFO SampleApp: Connected to UNIX socket client 0x10e5818 Jun 01 20:20:38 volumio qobuz-connect[16239]: 20260601 20:20:38.592 [16239.16239] INFO SampleApp: Playback volume changed: 75 Jun 01 20:20:38 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:39 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 01 20:20:39 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:39 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:39 volumio volumio[1249]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:39 volumio volumio[1249]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:39 volumio sudo[16268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 01 20:20:39 volumio sudo[16268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 20:20:39 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 01 20:20:39 volumio sudo[16268]: pam_unix(sudo:session): session closed for user root Jun 01 20:20:40 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:40 volumio volumio[1249]: info: Executing endpoint tc_getconfig Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 01 20:20:40 volumio volumio[1249]: info: TidalConnect service stoped! Jun 01 20:20:40 volumio vtcs[16271]: STARTING TidalConnect services, version: 1.6.1 Jun 01 20:20:40 volumio vtcs[16271]: STARTED TidalConnect services. Jun 01 20:20:40 volumio volumio[1249]: info: Executing endpoint tc_connect Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 01 20:20:40 volumio volumio[1249]: info: Connecting to TidalConnect Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:20:40 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:20:40 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:20:40 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:20:40 volumio volumio[1249]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Jun 01 20:20:40 volumio volumio[1249]: info: CoreStateMachine::pushState Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Jun 01 20:20:40 volumio volumio[1249]: info: MRS: Pushing multiroomSync output update for this device Jun 01 20:20:40 volumio volumio[1249]: info: MRS: Pushing multiroomSync output Jun 01 20:20:40 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Jun 01 20:20:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 52 Jun 01 20:20:40 volumio volumio[1249]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 01 20:20:40 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:40.060+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=75792 volume=100 Jun 01 20:20:40 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:40.060+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" state=STATUS_PLAYING positionMs=75792 volume=100 Jun 01 20:20:40 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:40.061+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:20:40 volumio volumio5-onboarding[1429]: time=2026-06-01T20:20:40.061+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:49926,00:00:00:00:00:00%02 @ 0x1cb2840" id=tidal://song/32823114 title="Born Slippy (Nuxx)" Jun 01 20:20:40 volumio volumio[1249]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:40 volumio volumio[1249]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 01 20:20:40 volumio volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 20:20:40 volumio volumio[1249]: Error: ENOENT: no such file or directory, unlink '/tmp/uoiFVDr4jj1fHbJya085H7G7XKpgAoesZAdrstIqEldO22kN3RhlfawrxcziYlNz3akcgPikwm4WJBYsN0MZ9xWJL96nmO2SGvOe' Jun 01 20:20:40 volumio volumio[1249]: at Object.unlinkSync (node:fs:1899:3) Jun 01 20:20:40 volumio volumio[1249]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11265) Jun 01 20:20:40 volumio volumio[1249]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Jun 01 20:20:40 volumio volumio[1249]: at TidalConnectClient.emit (node:events:514:28) Jun 01 20:20:40 volumio volumio[1249]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44713) Jun 01 20:20:40 volumio volumio[1249]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41683 Jun 01 20:20:40 volumio volumio[1249]: at Array.forEach () Jun 01 20:20:40 volumio volumio[1249]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41561) Jun 01 20:20:40 volumio volumio[1249]: at Socket.emit (node:events:514:28) Jun 01 20:20:40 volumio volumio[1249]: at addChunk (node:internal/streams/readable:343:12) Jun 01 20:20:40 volumio volumio[1249]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 01 20:20:40 volumio volumio[1249]: at Readable.push (node:internal/streams/readable:253:10) Jun 01 20:20:40 volumio volumio[1249]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Jun 01 20:20:40 volumio volumio[1249]: errno: -2, Jun 01 20:20:40 volumio volumio[1249]: syscall: 'unlink', Jun 01 20:20:40 volumio volumio[1249]: code: 'ENOENT', Jun 01 20:20:40 volumio volumio[1249]: path: '/tmp/uoiFVDr4jj1fHbJya085H7G7XKpgAoesZAdrstIqEldO22kN3RhlfawrxcziYlNz3akcgPikwm4WJBYsN0MZ9xWJL96nmO2SGvOe' Jun 01 20:20:40 volumio volumio[1249]: } Jun 01 20:20:40 volumio volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 20:20:40 volumio sudo[16309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 20:19' Jun 01 20:20:40 volumio sudo[16309]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"