Aug 26 21:35:01 volumio volumio[1019]: info: Preload queue cleared Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::ClearQueue Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::stop Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::stPlaybackTimer Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::updateTrackBlock Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrackBlock Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::serviceStop Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::serviceStop Aug 26 21:35:01 volumio volumio[1019]: info: [1787780101391] ControllerQobuz::stop Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:01 volumio volumio[1019]: info: ControllerMpd::stop Aug 26 21:35:01 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::clearPlayQueue Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::saveQueue Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPushQueue Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::addQueueItems Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::addQueueItems Aug 26 21:35:01 volumio volumio[1019]: info: Preload queue cleared Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/56277267 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/56277267 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.421Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_STOPPED positionMs=0 volume= Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.421Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/357122801 title="Mister Magic" Aug 26 21:35:01 volumio volumio[1019]: info: Aug 26 21:35:01 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:01 volumio volumio[1019]: info: sendMpdCommand stop took 61 milliseconds Aug 26 21:35:01 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:01 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:01 volumio volumio[1019]: info: sendMpdCommand status took 6 milliseconds Aug 26 21:35:01 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:01 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:01 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:01 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:01 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:01 volumio volumio[1019]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1131093&eid=357122801&c2=1&fmt=7&profile=raw&app_id=539451548&cid=2434453&etsp=1787783617&hmac=oIl2Dxh0XgYiX5isHc9nXk-DGOw","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=357122801&c2=1&fmt=7&profile=raw&app_id=539451548&cid=2434453&etsp=1787783617&hmac=oIl2Dxh0XgYiX5isHc9nXk-DGOw","trackType":"qobuz"} Aug 26 21:35:01 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::syncState stateService stop Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus stop Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:01 volumio volumio[1019]: info: No code Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:01 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.503Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=0 volume= Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.503Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/357122801 title="Mister Magic" Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.506Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=0 volume= Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.506Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/357122801 title="Mister Magic" Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.507Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=0 volume= Aug 26 21:35:01 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:01.507Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/357122801 title="Mister Magic" Aug 26 21:35:01 volumio volumio[1019]: info: ------------------------------ 66ms Aug 26 21:35:01 volumio volumio[1019]: info: explodeUri took 310 milliseconds Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPushQueue Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::saveQueue Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::updateTrackBlock Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrackBlock Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioPlay Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::play index 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::addQueueItems Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::addQueueItems Aug 26 21:35:01 volumio volumio[1019]: info: Preload queue cleared Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/365386299 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/365386299 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/174322649 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/174322649 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/321012280 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/321012280 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/52610353 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/52610353 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/762826 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/762826 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/3617428 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/3617428 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/328048754 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/328048754 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/2944927 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/2944927 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/209037147 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/209037147 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/33475354 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/33475354 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/314198774 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/314198774 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/2944930 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/2944930 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/111907482 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/111907482 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/60018416 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/60018416 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/33475353 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/33475353 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/314198778 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/314198778 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/7655987 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/7655987 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/314198776 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/314198776 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: Adding Item to queue: qobuz://song/53296590 Aug 26 21:35:01 volumio volumio[1019]: info: Exploding uri qobuz://song/53296590 in service qobuz Aug 26 21:35:01 volumio volumio[1019]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::stop Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::play index undefined Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:01 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:35:01 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 26 21:35:01 volumio volumio[1019]: info: [1787780101734] ControllerQobuz::clearAddPlayTrack Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 296 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 513 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 519 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 802 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 802 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 802 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 804 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 804 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 824 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 839 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 886 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 906 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 901 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 905 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 927 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 951 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 948 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 961 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: getStreamUrl took 976 milliseconds Aug 26 21:35:02 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 21:35:02 volumio volumio[1019]: info: sendMpdCommand stop took 1 milliseconds Aug 26 21:35:02 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 21:35:02 volumio volumio[1019]: info: Aug 26 21:35:02 volumio volumio[1019]: ---------------------------- MPD announces system playlist update Aug 26 21:35:02 volumio volumio[1019]: info: Ignoring MPD Status Update Aug 26 21:35:02 volumio volumio[1019]: info: sendMpdCommand clear took 2 milliseconds Aug 26 21:35:02 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo" Aug 26 21:35:02 volumio volumio[1019]: error: updateQueue error: null Aug 26 21:35:02 volumio volumio[1019]: info: ------------------------------ 3ms Aug 26 21:35:02 volumio volumio[1019]: info: explodeUri took 1002 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: CoreCommandRouter::volumioPushQueue Aug 26 21:35:02 volumio volumio[1019]: info: CorePlayQueue::saveQueue Aug 26 21:35:02 volumio volumio[1019]: info: CoreStateMachine::updateTrackBlock Aug 26 21:35:02 volumio volumio[1019]: info: CorePlayQueue::getTrackBlock Aug 26 21:35:02 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo" Aug 26 21:35:02 volumio volumio[1019]: info: Aug 26 21:35:02 volumio volumio[1019]: ---------------------------- MPD announces system playlist update Aug 26 21:35:02 volumio volumio[1019]: info: Ignoring MPD Status Update Aug 26 21:35:02 volumio volumio[1019]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo" took 1 milliseconds Aug 26 21:35:02 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:02 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand play Aug 26 21:35:02 volumio volumio[1019]: info: ------------------------------ 2ms Aug 26 21:35:02 volumio volumio[1019]: info: sendMpdCommand play took 2 milliseconds Aug 26 21:35:03 volumio volumio[1019]: info: Aug 26 21:35:03 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:03 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:03 volumio volumio[1019]: info: Aug 26 21:35:03 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:03 volumio volumio[1019]: info: sendMpdCommand status took 34 milliseconds Aug 26 21:35:03 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:03 volumio volumio[1019]: info: sendMpdCommand status took 1 milliseconds Aug 26 21:35:03 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:03 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:03 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:03 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"405 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:03 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:03 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:03 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus stop Aug 26 21:35:03 volumio volumio[1019]: info: ------------------------------ 39ms Aug 26 21:35:03 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 21:35:03 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:03 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:03 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:03 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"416 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:03 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:03 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:03 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:03 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:03 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:03 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:03 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:03 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:03 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:03 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:03 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:03 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:03.290Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=0 volume= Aug 26 21:35:03 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:03.293Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:03 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:03.296Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=0 volume= Aug 26 21:35:03 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:03.297Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:03 volumio volumio[1019]: info: ------------------------------ 51ms Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioSeek Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::seek Aug 26 21:35:06 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:06 volumio volumio[1019]: info: TRACKBLOCK {"album":"Asian Roots","albumUri":"qobuz://album/vzmzzf0sv4zpa","albumart":"https://static.qobuz.com/images/covers/pa/4z/vzmzzf0sv4zpa_600.jpg","artist":"TakéDaké","artistUri":"qobuz://artist/783667","audioQuality":"","bitdepth":"16 bit","duration":456,"explicit":false,"name":"Japanese Roots","samplerate":"44 KHz","service":"qobuz","title":"Japanese Roots","trackType":"qobuz","tracknumber":6,"type":"track","uri":"qobuz://song/56277267","volumeNumber":1,"tagImage":""} Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:06 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:06 volumio volumio[1019]: info: [1787780106597] ControllerQobuz::seek Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:06 volumio volumio[1019]: info: ControllerMpd::seek Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:06 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:06 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:06 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:06.645Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=52000 volume= Aug 26 21:35:06 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:06.645Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:06 volumio volumio[1019]: error: null Aug 26 21:35:06 volumio volumio[1019]: info: Aug 26 21:35:06 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:06 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:06 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:06 volumio volumio[1019]: info: sendMpdCommand status took 13 milliseconds Aug 26 21:35:06 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:06 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:06 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:06 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:06 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:06 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:06 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"730 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:06 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:06 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:06 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:06 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:06 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:06 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:06 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:06 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:06 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:06.707Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=52000 volume= Aug 26 21:35:06 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:06.708Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:06 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:06.711Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=52000 volume= Aug 26 21:35:06 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:06.711Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:06 volumio volumio[1019]: info: ------------------------------ 51ms Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioSeek Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::seek Aug 26 21:35:09 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:09 volumio volumio[1019]: info: TRACKBLOCK {"album":"Asian Roots","albumUri":"qobuz://album/vzmzzf0sv4zpa","albumart":"https://static.qobuz.com/images/covers/pa/4z/vzmzzf0sv4zpa_600.jpg","artist":"TakéDaké","artistUri":"qobuz://artist/783667","audioQuality":"","bitdepth":"16 bit","duration":456,"explicit":false,"name":"Japanese Roots","samplerate":"44 KHz","service":"qobuz","title":"Japanese Roots","trackType":"qobuz","tracknumber":6,"type":"track","uri":"qobuz://song/56277267","volumeNumber":1,"tagImage":""} Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:09 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:09 volumio volumio[1019]: info: [1787780109057] ControllerQobuz::seek Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:09 volumio volumio[1019]: info: ControllerMpd::seek Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:09 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:09 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:09 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:09.099Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=102000 volume= Aug 26 21:35:09 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:09.100Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:09 volumio volumio[1019]: error: null Aug 26 21:35:09 volumio volumio[1019]: info: Aug 26 21:35:09 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:09 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:09 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:09 volumio volumio[1019]: info: sendMpdCommand status took 8 milliseconds Aug 26 21:35:09 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:09 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:09 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:09 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:09 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:09 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:09 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":103209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"650 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:09 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:09 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:09 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:09 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:09 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:09 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:09 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:09 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:09 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:09.148Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=102045 volume= Aug 26 21:35:09 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:09.149Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:09 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:09.152Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=102045 volume= Aug 26 21:35:09 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:09.153Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:09 volumio volumio[1019]: info: ------------------------------ 55ms Aug 26 21:35:10 volumio volumio[1019]: info: Adding qobuz://song/56277267 to favourites with specific qobuz method Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioSeek Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::seek Aug 26 21:35:16 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:16 volumio volumio[1019]: info: TRACKBLOCK {"album":"Asian Roots","albumUri":"qobuz://album/vzmzzf0sv4zpa","albumart":"https://static.qobuz.com/images/covers/pa/4z/vzmzzf0sv4zpa_600.jpg","artist":"TakéDaké","artistUri":"qobuz://artist/783667","audioQuality":"","bitdepth":"16 bit","duration":456,"explicit":false,"name":"Japanese Roots","samplerate":"44 KHz","service":"qobuz","title":"Japanese Roots","trackType":"qobuz","tracknumber":6,"type":"track","uri":"qobuz://song/56277267","volumeNumber":1,"tagImage":""} Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:16 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:16 volumio volumio[1019]: info: [1787780116059] ControllerQobuz::seek Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:16 volumio volumio[1019]: info: ControllerMpd::seek Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:16 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:16 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:16 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:16.082Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=145000 volume= Aug 26 21:35:16 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:16.083Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:16 volumio volumio[1019]: error: null Aug 26 21:35:16 volumio volumio[1019]: info: Aug 26 21:35:16 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:16 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:16 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:16 volumio volumio[1019]: info: sendMpdCommand status took 14 milliseconds Aug 26 21:35:16 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:16 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:16 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:16 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:16 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:16 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:16 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":146209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"671 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:16 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:16 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:16 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:16 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:16 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:16 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:16 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:16 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:16 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:16.145Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=145035 volume= Aug 26 21:35:16 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:16.146Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:16 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:16.148Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=145035 volume= Aug 26 21:35:16 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:16.148Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:16 volumio volumio[1019]: info: ------------------------------ 59ms Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioSeek Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::seek Aug 26 21:35:26 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:26 volumio volumio[1019]: info: TRACKBLOCK {"album":"Asian Roots","albumUri":"qobuz://album/vzmzzf0sv4zpa","albumart":"https://static.qobuz.com/images/covers/pa/4z/vzmzzf0sv4zpa_600.jpg","artist":"TakéDaké","artistUri":"qobuz://artist/783667","audioQuality":"","bitdepth":"16 bit","duration":456,"explicit":false,"name":"Japanese Roots","samplerate":"44 KHz","service":"qobuz","title":"Japanese Roots","trackType":"qobuz","tracknumber":6,"type":"track","uri":"qobuz://song/56277267","volumeNumber":1,"tagImage":""} Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:26 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:26 volumio volumio[1019]: info: [1787780126576] ControllerQobuz::seek Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:26 volumio volumio[1019]: info: ControllerMpd::seek Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:26 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:26 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:26 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:26.594Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=221000 volume= Aug 26 21:35:26 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:26.598Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:26 volumio volumio[1019]: error: null Aug 26 21:35:26 volumio volumio[1019]: info: Aug 26 21:35:26 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:26 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:26 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:26 volumio volumio[1019]: info: sendMpdCommand status took 8 milliseconds Aug 26 21:35:26 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:26 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:26 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:26 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:26 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:26 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:26 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":222209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"562 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:26 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:26 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:26 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:26 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:26 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:26 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:26 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:26 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:26 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:26.639Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=221023 volume= Aug 26 21:35:26 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:26.640Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:26 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:26.641Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=221023 volume= Aug 26 21:35:26 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:26.641Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:26 volumio volumio[1019]: info: ------------------------------ 47ms Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioSeek Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::seek Aug 26 21:35:28 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:28 volumio volumio[1019]: info: TRACKBLOCK {"album":"Asian Roots","albumUri":"qobuz://album/vzmzzf0sv4zpa","albumart":"https://static.qobuz.com/images/covers/pa/4z/vzmzzf0sv4zpa_600.jpg","artist":"TakéDaké","artistUri":"qobuz://artist/783667","audioQuality":"","bitdepth":"16 bit","duration":456,"explicit":false,"name":"Japanese Roots","samplerate":"44 KHz","service":"qobuz","title":"Japanese Roots","trackType":"qobuz","tracknumber":6,"type":"track","uri":"qobuz://song/56277267","volumeNumber":1,"tagImage":""} Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:28 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:28 volumio volumio[1019]: info: [1787780128803] ControllerQobuz::seek Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:28 volumio volumio[1019]: info: ControllerMpd::seek Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:28 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:28 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:28 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:28.827Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=296000 volume= Aug 26 21:35:28 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:28.827Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:28 volumio volumio[1019]: error: null Aug 26 21:35:28 volumio volumio[1019]: info: Aug 26 21:35:28 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:28 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:28 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:28 volumio volumio[1019]: info: sendMpdCommand status took 3 milliseconds Aug 26 21:35:28 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:28 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:28 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:28 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:28 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:28 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:28 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:28 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:28 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:28 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:28 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:28 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:28 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:28 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:28 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:28 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:28.871Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=296033 volume= Aug 26 21:35:28 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:28.871Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:28 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:28.871Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=296033 volume= Aug 26 21:35:28 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:28.871Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:28 volumio volumio[1019]: info: ------------------------------ 47ms Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioSeek Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::seek Aug 26 21:35:41 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:41 volumio volumio[1019]: info: TRACKBLOCK {"album":"Asian Roots","albumUri":"qobuz://album/vzmzzf0sv4zpa","albumart":"https://static.qobuz.com/images/covers/pa/4z/vzmzzf0sv4zpa_600.jpg","artist":"TakéDaké","artistUri":"qobuz://artist/783667","audioQuality":"","bitdepth":"16 bit","duration":456,"explicit":false,"name":"Japanese Roots","samplerate":"44 KHz","service":"qobuz","title":"Japanese Roots","trackType":"qobuz","tracknumber":6,"type":"track","uri":"qobuz://song/56277267","volumeNumber":1,"tagImage":""} Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::startPlaybackTimer Aug 26 21:35:41 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:41 volumio volumio[1019]: info: [1787780141344] ControllerQobuz::seek Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 21:35:41 volumio volumio[1019]: info: ControllerMpd::seek Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:41 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:41 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:41 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:41.366Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=362000 volume= Aug 26 21:35:41 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:41.367Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:41 volumio volumio[1019]: error: null Aug 26 21:35:41 volumio volumio[1019]: info: Aug 26 21:35:41 volumio volumio[1019]: ---------------------------- MPD announces state update: player Aug 26 21:35:41 volumio volumio[1019]: info: ControllerMpd::getState Aug 26 21:35:41 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand status Aug 26 21:35:41 volumio volumio[1019]: info: sendMpdCommand status took 7 milliseconds Aug 26 21:35:41 volumio volumio[1019]: verbose: ControllerMpd::parseState Aug 26 21:35:41 volumio volumio[1019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 21:35:41 volumio volumio[1019]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 21:35:41 volumio volumio[1019]: verbose: ControllerMpd::parseTrackInfo Aug 26 21:35:41 volumio volumio[1019]: info: ControllerMpd::pushState Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::servicePushState Aug 26 21:35:41 volumio volumio[1019]: info: CorePlayQueue::getTrack 0 Aug 26 21:35:41 volumio volumio[1019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":363209,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"713 Kbps","isStreaming":false,"title":"file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1131093&eid=56277267&fmt=6&profile=raw&app_id=539451548&cid=2434453&etsp=1787783702&hmac=abB2A8CKuwOF1fAijPXf5LzerUo","trackType":"qobuz"} Aug 26 21:35:41 volumio volumio[1019]: verbose: CURRENT POSITION 0 Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::syncState stateService play Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::syncState currentStatus play Aug 26 21:35:41 volumio volumio[1019]: info: Received an update from plugin. extracting info from payload Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:41 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:41 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:41 volumio volumio[1019]: info: CoreStateMachine::pushState Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioPushState Aug 26 21:35:41 volumio volumio[1019]: info: MRS: Pushing multiroomSync output update for this device Aug 26 21:35:41 volumio volumio[1019]: info: MRS: Pushing multiroomSync output Aug 26 21:35:41 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:41 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:41.418Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=362024 volume= Aug 26 21:35:41 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:41.419Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:41 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:41.420Z level=INFO msg="emitting player state changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" state=STATUS_PLAYING positionMs=362024 volume= Aug 26 21:35:41 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:41.421Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.20:52514 @ 0xc000534c30" id=qobuz://song/56277267 title="Japanese Roots" Aug 26 21:35:41 volumio volumio[1019]: info: ------------------------------ 57ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.202Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.229Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=26.953926ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.242Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=37.88018ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.247Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=43.331487ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.251Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://securetoken.googleapis.com duration=48.153543ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.257Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=53.413652ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.272Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://database.volumio.cloud duration=67.928949ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.273Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://functions.volumio.cloud duration=70.729034ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.275Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://functions.volumio.cloud duration=71.597794ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.327Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://google.com duration=123.303636ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.327Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=http://pushupdates.volumio.org duration=122.675479ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.329Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=https://www.googleapis.com duration=124.612923ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.451Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=http://plugins.volumio.org duration=247.683013ms Aug 26 21:35:52 volumio volumio5-onboarding[2082]: time=2026-08-26T21:35:52.507Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.4.20:52514 @ 0xc000534c30" latency=77.159232ms timeout=10s endpoint=http://cddb.volumio.org duration=303.0031ms Aug 26 21:35:53 volumio volumio[1019]: verbose: New Socket.io Connection to 192.168.4.44 from 192.168.4.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::volumioGetQueue Aug 26 21:35:53 volumio volumio[1019]: info: CoreStateMachine::getQueue Aug 26 21:35:53 volumio volumio[1019]: info: CorePlayQueue::getQueue Aug 26 21:35:53 volumio volumio[1019]: info: Listing playlists Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 21:35:53 volumio volumio[1019]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 21:35:53 volumio volumio[1019]: info: Received Get System Info Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:35:53 volumio volumio[1019]: info: Discovery: Getting this device information Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:53 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 21:35:54 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 21:35:54 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 21:35:54 volumio volumio[1019]: info: Discovery: Getting this device information Aug 26 21:35:54 volumio volumio[1019]: info: CoreCommandRouter::volumioGetState Aug 26 21:35:54 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 21:35:54 volumio volumio[1019]: verbose: New Socket.io Connection to 192.168.4.44:3000 from 192.168.4.20 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 26 21:35:54 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 21:35:54 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 21:35:58 volumio volumio[1019]: Searching all installed plugins Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 26 21:35:58 volumio volumio[1019]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 26 21:35:58 volumio volumio[1019]: Searching all installed plugins Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 26 21:35:58 volumio volumio[1019]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 26 21:35:58 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 26 21:35:59 volumio volumio[1019]: info: searchQOBUZUri took 1037 milliseconds Aug 26 21:35:59 volumio volumio[1019]: info: All search sources collected, pushing search results Aug 26 21:35:59 volumio volumio[1019]: info: searchQOBUZUri took 1142 milliseconds Aug 26 21:35:59 volumio volumio[1019]: info: All search sources collected, pushing search results Aug 26 21:36:02 volumio volumio[1019]: Searching all installed plugins Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: , search Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 26 21:36:02 volumio volumio[1019]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 26 21:36:02 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 26 21:36:03 volumio volumio[1019]: Searching all installed plugins Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: , search Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Aug 26 21:36:03 volumio volumio[1019]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: webradio , search Aug 26 21:36:03 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 26 21:36:03 volumio volumio[1019]: info: searchQOBUZUri took 1000 milliseconds Aug 26 21:36:03 volumio volumio[1019]: info: All search sources collected, pushing search results Aug 26 21:36:03 volumio volumio[1019]: info: searchQOBUZUri took 200 milliseconds Aug 26 21:36:03 volumio volumio[1019]: info: All search sources collected, pushing search results Aug 26 21:36:07 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 26 21:36:07 volumio volumio[1019]: info: handleBrowseUri took 606 milliseconds Aug 26 21:36:07 volumio volumio[1019]: info: Preload queue cleared Aug 26 21:36:08 volumio volumio[1019]: info: Executing endpoint getSimilarArtists Aug 26 21:36:08 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 21:36:08 volumio volumio[1019]: info: Executing endpoint getSimilarArtists Aug 26 21:36:08 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 21:36:08 volumio volumio[1019]: info: Executing endpoint getSimilarArtists Aug 26 21:36:08 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 21:36:08 volumio volumio[1019]: info: Executing endpoint metavolumio Aug 26 21:36:08 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 21:36:08 volumio volumio[1019]: info: Executing endpoint metavolumio Aug 26 21:36:08 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 21:36:08 volumio volumio[1019]: info: Executing endpoint metavolumio Aug 26 21:36:08 volumio volumio[1019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 21:36:10 volumio volumio[1019]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 21:36:10 volumio volumio[1019]: TypeError: Cannot read properties of null (reading 'slice') Aug 26 21:36:10 volumio volumio[1019]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 26 21:36:10 volumio volumio[1019]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 26 21:36:10 volumio volumio[1019]: at Object.onceWrapper (node:events:629:26) Aug 26 21:36:10 volumio volumio[1019]: at ClientRequest.emit (node:events:514:28) Aug 26 21:36:10 volumio volumio[1019]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Aug 26 21:36:10 volumio volumio[1019]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Aug 26 21:36:10 volumio volumio[1019]: at TLSSocket.socketOnData (node:_http_client:535:22) Aug 26 21:36:10 volumio volumio[1019]: at TLSSocket.emit (node:events:514:28) Aug 26 21:36:10 volumio volumio[1019]: at addChunk (node:internal/streams/readable:343:12) Aug 26 21:36:10 volumio volumio[1019]: at readableAddChunk (node:internal/streams/readable:316:9) Aug 26 21:36:10 volumio volumio[1019]: at Readable.push (node:internal/streams/readable:253:10) Aug 26 21:36:10 volumio volumio[1019]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Aug 26 21:36:10 volumio volumio[1019]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 21:36:10 volumio sudo[4587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 21:35' Aug 26 21:36:10 volumio sudo[4587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"