Aug 31 16:17:49 primo-plus volumio[1168]: info: Preload queue cleared Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::ClearQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::stop Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::updateTrackBlock Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrackBlock Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 0 Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 0 Aug 31 16:17:49 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:49 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::serviceStop Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 0 Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::serviceStop Aug 31 16:17:49 primo-plus volumio[1168]: info: [1788182269857] ControllerQobuz::stop Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:17:49 primo-plus volumio[1168]: info: ControllerMpd::stop Aug 31 16:17:49 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::clearPlayQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::saveQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::addQueueItems Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::addQueueItems Aug 31 16:17:49 primo-plus volumio[1168]: info: Preload queue cleared Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/411794383 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/411794383 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/437977740 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/437977740 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/408904133 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/408904133 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/341615535 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/341615535 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/19251184 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/19251184 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/209006214 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/209006214 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/381790668 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/381790668 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/429603174 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/429603174 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/270239417 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/270239417 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/200692644 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/200692644 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/200692649 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/200692649 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/173637770 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/173637770 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/414589485 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/414589485 Aug 31 16:17:49 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:49.868+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_STOPPED positionMs=0 volume=44 Aug 31 16:17:49 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:49.868+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/411794383 title=RearView Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::saveQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::updateTrackBlock Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrackBlock Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPlay Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::play index 12 Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::addQueueItems Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::addQueueItems Aug 31 16:17:49 primo-plus volumio[1168]: info: Preload queue cleared Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/259562404 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/259562404 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/319863929 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/319863929 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/415667274 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/415667274 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/366740742 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/366740742 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/89306280 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/89306280 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/200999891 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/200999891 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/44331335 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/44331335 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/6180431 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/6180431 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/47101890 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/47101890 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/47101889 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/47101889 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/47101887 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/47101887 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/203861596 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/203861596 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/30377598 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/30377598 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/16104033 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/16104033 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/131558998 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/131558998 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/27582763 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/27582763 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/326054305 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/326054305 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/34736401 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/34736401 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/62708011 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/62708011 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/171347 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/171347 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/307234504 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/307234504 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/183852574 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/183852574 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/123798440 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/123798440 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/93823710 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/93823710 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/17781412 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/17781412 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/16304049 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/16304049 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/16304035 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/16304035 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/7166386 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/7166386 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/284412072 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/284412072 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/159168401 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/159168401 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/74059918 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/74059918 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/231781850 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/231781850 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/152228619 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/152228619 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/140601146 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/140601146 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/342795441 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/342795441 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/157508155 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/157508155 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/33737638 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/33737638 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/360724747 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/360724747 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/45803075 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/45803075 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/25667962 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/25667962 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/25667970 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/25667970 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/81780887 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/81780887 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/70542 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/70542 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/70543 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/70543 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/31437134 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/31437134 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/222685404 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/222685404 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/26884416 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/26884416 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/114457078 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/114457078 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/303777006 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/303777006 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/282871190 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/282871190 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/45973124 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/45973124 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/397158487 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/397158487 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/98899163 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/98899163 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/188378129 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/188378129 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/119216409 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/119216409 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/67771596 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/67771596 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/51102834 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/51102834 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/89189411 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/89189411 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/89189410 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/89189410 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/61603264 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/61603264 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/210295067 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/210295067 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/214190180 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/214190180 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/57611599 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/57611599 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/57611595 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/57611595 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/232519491 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/232519491 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/232519490 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/232519490 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/130093499 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/130093499 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/130093490 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/130093490 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/41734 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/41734 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/54992275 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/54992275 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/51779845 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/51779845 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/51779846 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/51779846 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/378054811 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/378054811 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/61917093 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/61917093 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/19108909 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/19108909 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/205818163 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/205818163 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/142361380 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/142361380 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/193843206 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/193843206 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/4874279 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/4874279 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/80142587 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/80142587 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/129997391 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/129997391 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/327636886 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/327636886 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/303842370 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/303842370 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/345647668 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/345647668 Aug 31 16:17:49 primo-plus volumio[1168]: info: Adding Item to queue: qobuz://song/61847066 Aug 31 16:17:49 primo-plus volumio[1168]: info: Using cached record of: qobuz://song/61847066 Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::stop Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::saveQueue Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::play index undefined Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::updateTrackBlock Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrackBlock Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:49 primo-plus volumio[1168]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:17:49 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:49 primo-plus volumio[1168]: info: [1788182269962] ControllerQobuz::clearAddPlayTrack Aug 31 16:17:50 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand stop took 154 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:50 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:50 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand status took 10 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand status took 10 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:50 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:50 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Aug 31 16:17:50 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:50 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Aug 31 16:17:50 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:50 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:50 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:50 primo-plus volumio[1168]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Aug 31 16:17:50 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:50.049+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_STOPPED positionMs=54 volume=44 Aug 31 16:17:50 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:50.050+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:50 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:50.051+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_STOPPED positionMs=54 volume=44 Aug 31 16:17:50 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:50.051+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:50 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:50.052+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_STOPPED positionMs=54 volume=44 Aug 31 16:17:50 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:50.052+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 46ms Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 45ms Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 45ms Aug 31 16:17:50 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:50 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:50 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:50 primo-plus volumio[1168]: info: MCU Signalled Playback Inactive Aug 31 16:17:50 primo-plus volumio[1168]: info: getStreamUrl took 551 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand stop took 1 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces system playlist update Aug 31 16:17:50 primo-plus volumio[1168]: info: Ignoring MPD Status Update Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand clear took 4 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE" Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces system playlist update Aug 31 16:17:50 primo-plus volumio[1168]: info: Ignoring MPD Status Update Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces system playlist update Aug 31 16:17:50 primo-plus volumio[1168]: info: Ignoring MPD Status Update Aug 31 16:17:50 primo-plus volumio[1168]: error: updateQueue error: null Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 2ms Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE" Aug 31 16:17:50 primo-plus volumio[1168]: error: updateQueue error: null Aug 31 16:17:50 primo-plus volumio[1168]: error: updateQueue error: null Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 271ms Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 271ms Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces system playlist update Aug 31 16:17:50 primo-plus volumio[1168]: info: Ignoring MPD Status Update Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE" took 1 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:17:50 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces system playlist update Aug 31 16:17:50 primo-plus volumio[1168]: info: Ignoring MPD Status Update Aug 31 16:17:50 primo-plus volumio[1168]: info: Aug 31 16:17:50 primo-plus volumio[1168]: ---------------------------- MPD announces system playlist update Aug 31 16:17:50 primo-plus volumio[1168]: info: Ignoring MPD Status Update Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 2ms Aug 31 16:17:50 primo-plus volumio[1168]: info: sendMpdCommand play took 2 milliseconds Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 3ms Aug 31 16:17:50 primo-plus volumio[1168]: info: ------------------------------ 2ms Aug 31 16:17:51 primo-plus volumio[1168]: info: Aug 31 16:17:51 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:51 primo-plus volumio[1168]: info: Aug 31 16:17:51 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:51 primo-plus volumio[1168]: info: Aug 31 16:17:51 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:51 primo-plus volumio[1168]: info: Aug 31 16:17:51 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:51 primo-plus volumio[1168]: info: Aug 31 16:17:51 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:51 primo-plus volumio[1168]: info: Aug 31 16:17:51 primo-plus volumio[1168]: ---------------------------- MPD announces state update: player Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::getState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseState Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:51 primo-plus volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","trackType":"qobuz"} Aug 31 16:17:51 primo-plus volumio[1168]: verbose: CURRENT POSITION 12 Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState stateService play Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:51 primo-plus volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","trackType":"qobuz"} Aug 31 16:17:51 primo-plus volumio[1168]: verbose: CURRENT POSITION 12 Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState stateService play Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:17:51 primo-plus volumio[1168]: info: Received an update from plugin. extracting info from payload Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:51 primo-plus volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","trackType":"qobuz"} Aug 31 16:17:51 primo-plus volumio[1168]: verbose: CURRENT POSITION 12 Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState stateService play Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:17:51 primo-plus volumio[1168]: info: Received an update from plugin. extracting info from payload Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:51 primo-plus volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","trackType":"qobuz"} Aug 31 16:17:51 primo-plus volumio[1168]: verbose: CURRENT POSITION 12 Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState stateService play Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:17:51 primo-plus volumio[1168]: info: Received an update from plugin. extracting info from payload Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:51 primo-plus volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","trackType":"qobuz"} Aug 31 16:17:51 primo-plus volumio[1168]: verbose: CURRENT POSITION 12 Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState stateService play Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:17:51 primo-plus volumio[1168]: info: Received an update from plugin. extracting info from payload Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: ------------------------------ 57ms Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.083+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.083+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.084+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.084+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.085+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.085+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.087+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.087+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.087+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.088+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.088+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.089+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.089+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.089+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio[1168]: info: ------------------------------ 89ms Aug 31 16:17:51 primo-plus volumio[1168]: info: ------------------------------ 89ms Aug 31 16:17:51 primo-plus volumio[1168]: info: ------------------------------ 89ms Aug 31 16:17:51 primo-plus volumio[1168]: info: ------------------------------ 89ms Aug 31 16:17:51 primo-plus volumio[1168]: info: sendMpdCommand playlistinfo took 83 milliseconds Aug 31 16:17:51 primo-plus volumio[1168]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:17:51 primo-plus volumio[1168]: info: ControllerMpd::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::servicePushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CorePlayQueue::getTrack 12 Aug 31 16:17:51 primo-plus volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2421354&eid=414589485&fmt=6&profile=raw&app_id=539451548&cid=1684396&etsp=1788185870&hmac=5zS3753TS_zLJU1NTZEVmeNDfwE","trackType":"qobuz"} Aug 31 16:17:51 primo-plus volumio[1168]: verbose: CURRENT POSITION 12 Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState stateService play Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:17:51 primo-plus volumio[1168]: info: Received an update from plugin. extracting info from payload Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreStateMachine::pushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioPushState Aug 31 16:17:51 primo-plus volumio[1168]: info: CoreCommandRouter::volumioGetState Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output update for this device Aug 31 16:17:51 primo-plus volumio[1168]: info: MRS: Pushing multiroomSync output Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.122+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.123+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.123+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" state=STATUS_PLAYING positionMs=0 volume=44 Aug 31 16:17:51 primo-plus volumio5-onboarding[2462]: time=2026-08-31T16:17:51.123+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.111.25:56476 @ 0x14a90b0" id=qobuz://song/414589485 title="Step by Step" Aug 31 16:17:51 primo-plus volumio[1168]: info: ------------------------------ 110ms Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Signalling Playback active due to playback status change Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: Updating RAAT Signal Path Aug 31 16:17:51 primo-plus volumio[1168]: info: MCU Signalled Playback Active Aug 31 16:18:33 primo-plus volumio[1168]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Aug 31 16:18:33 primo-plus volumio[1168]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:18:33 primo-plus volumio[1168]: TypeError: Cannot read properties of undefined (reading 'then') Aug 31 16:18:33 primo-plus volumio[1168]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Aug 31 16:18:33 primo-plus volumio[1168]: at Socket.emit (node:events:514:28) Aug 31 16:18:33 primo-plus volumio[1168]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 31 16:18:33 primo-plus volumio[1168]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 31 16:18:33 primo-plus volumio[1168]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:18:33 primo-plus sudo[16961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 16:17' Aug 31 16:18:33 primo-plus sudo[16961]: 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="ceea798be624bcca033d94ae449c2a749a9724f0" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 27 09:37:15 UTC 2026" VOLUMIO_VERSION="4.164" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="c8e7083e83ff605518b1cfc23784b0e7"