-- Logs begin at Wed 2026-01-07 23:20:13 JST, end at Sun 2026-01-25 18:51:03 JST. -- Jan 25 18:50:00 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:00 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:00 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:00 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:00.029+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x16e87f8 Jan 25 18:50:00 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:00.032+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x16e87f8 Jan 25 18:50:02 rivo volumio[3480]: info: Preload queue cleared Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::ClearQueue Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::updateTrackBlock Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrackBlock Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::stPlaybackTimer Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrack 6 Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrack 6 Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::serviceStop Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrack 6 Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::serviceStop Jan 25 18:50:02 rivo volumio[3480]: info: [1769334602645] ControllerQobuz::stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::stop Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::clearPlayQueue Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::saveQueue Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushQueue Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::addQueueItems Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::addQueueItems Jan 25 18:50:02 rivo volumio[3480]: info: Preload queue cleared Jan 25 18:50:02 rivo volumio[3480]: info: Adding Item to queue: qobuz://song/292734087 Jan 25 18:50:02 rivo volumio[3480]: info: Exploding uri qobuz://song/292734087 in service qobuz Jan 25 18:50:02 rivo volumio[3480]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 25 18:50:02 rivo volumio[3480]: info: Jan 25 18:50:02 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand stop took 25 milliseconds Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand status took 1 milliseconds Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:02 rivo volumio[3480]: info: Jan 25 18:50:02 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:02 rivo volumio[3480]: info: Jan 25 18:50:02 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand status took 3 milliseconds Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand status took 2 milliseconds Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrack 6 Jan 25 18:50:02 rivo volumio[3480]: 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":"701460","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/701460","trackType":"qobuz"} Jan 25 18:50:02 rivo volumio[3480]: verbose: CURRENT POSITION 6 Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::syncState stateService stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: No code Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: ------------------------------ 40ms Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 33 milliseconds Jan 25 18:50:02 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 33 milliseconds Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:02 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrack 6 Jan 25 18:50:02 rivo volumio[3480]: 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":"701460","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/701460","trackType":"qobuz"} Jan 25 18:50:02 rivo volumio[3480]: verbose: CURRENT POSITION 6 Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::syncState stateService stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: No code Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: CorePlayQueue::getTrack 6 Jan 25 18:50:02 rivo volumio[3480]: 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":"701460","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/701460","trackType":"qobuz"} Jan 25 18:50:02 rivo volumio[3480]: verbose: CURRENT POSITION 6 Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::syncState stateService stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: No code Jan 25 18:50:02 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:02 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:02 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:02 rivo volumio[3480]: info: ------------------------------ 85ms Jan 25 18:50:02 rivo volumio[3480]: info: ------------------------------ 85ms Jan 25 18:50:03 rivo volumio[3480]: info: explodeUri took 611 milliseconds Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::volumioPushQueue Jan 25 18:50:03 rivo volumio[3480]: info: CorePlayQueue::saveQueue Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::updateTrackBlock Jan 25 18:50:03 rivo volumio[3480]: info: CorePlayQueue::getTrackBlock Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::volumioPlay Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::play index 0 Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::stop Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::play index undefined Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 18:50:03 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::startPlaybackTimer Jan 25 18:50:03 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::volumioGetVisibleSources Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 25 18:50:03 rivo volumio[3480]: info: [1769334603270] ControllerQobuz::clearAddPlayTrack Jan 25 18:50:03 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 18:50:03 rivo volumio[3480]: info: sendMpdCommand stop took 4 milliseconds Jan 25 18:50:03 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 18:50:03 rivo volumio[3480]: info: Jan 25 18:50:03 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Jan 25 18:50:03 rivo volumio[3480]: info: Ignoring MPD Status Update Jan 25 18:50:03 rivo volumio[3480]: info: sendMpdCommand clear took 2 milliseconds Jan 25 18:50:03 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/292734087" Jan 25 18:50:03 rivo volumio[3480]: info: Jan 25 18:50:03 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Jan 25 18:50:03 rivo volumio[3480]: info: Ignoring MPD Status Update Jan 25 18:50:03 rivo volumio[3480]: info: Jan 25 18:50:03 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Jan 25 18:50:03 rivo volumio[3480]: info: Ignoring MPD Status Update Jan 25 18:50:03 rivo volumio[3480]: STREAMING PROXY: Handling url /?data=qobuz://song/292734087 Jan 25 18:50:03 rivo volumio[3480]: error: updateQueue error: null Jan 25 18:50:03 rivo volumio[3480]: info: ------------------------------ 5ms Jan 25 18:50:03 rivo volumio[3480]: info: Executing endpoint getStreamUrlqobuz Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 25 18:50:03 rivo volumio[3480]: info: getStreamUrl took 547 milliseconds Jan 25 18:50:03 rivo volumio[3480]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3508003&eid=292734087&fmt=7&profile=raw&app_id=539451548&cid=2508668&etsp=1769338203&hmac=s949F-WNF8nwSs7QYDWh2u0n8vA Jan 25 18:50:03 rivo volumio[3480]: STREAMING PROXY: Response: 200, length: 104214719 Jan 25 18:50:03 rivo volumio[3480]: STREAMING PROXY: Client dropped request, destroying Jan 25 18:50:03 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/292734087" Jan 25 18:50:03 rivo volumio[3480]: error: updateQueue error: null Jan 25 18:50:03 rivo volumio[3480]: error: updateQueue error: null Jan 25 18:50:03 rivo volumio[3480]: info: Jan 25 18:50:03 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Jan 25 18:50:03 rivo volumio[3480]: info: Ignoring MPD Status Update Jan 25 18:50:03 rivo volumio[3480]: info: ------------------------------ 690ms Jan 25 18:50:03 rivo volumio[3480]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/292734087" took 3 milliseconds Jan 25 18:50:03 rivo volumio[3480]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 18:50:03 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand play Jan 25 18:50:03 rivo volumio[3480]: info: ------------------------------ 3ms Jan 25 18:50:03 rivo volumio[3480]: info: sendMpdCommand play took 3 milliseconds Jan 25 18:50:03 rivo volumio[3480]: info: Jan 25 18:50:03 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Jan 25 18:50:03 rivo volumio[3480]: info: Ignoring MPD Status Update Jan 25 18:50:03 rivo volumio[3480]: STREAMING PROXY: Handling url /?data=qobuz://song/292734087 Jan 25 18:50:03 rivo volumio[3480]: info: Jan 25 18:50:03 rivo volumio[3480]: ---------------------------- MPD announces system playlist update Jan 25 18:50:03 rivo volumio[3480]: info: Ignoring MPD Status Update Jan 25 18:50:03 rivo volumio[3480]: info: ------------------------------ 4ms Jan 25 18:50:03 rivo volumio[3480]: info: ------------------------------ 3ms Jan 25 18:50:03 rivo volumio[3480]: info: Executing endpoint getStreamUrlqobuz Jan 25 18:50:03 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 25 18:50:04 rivo volumio[3480]: info: getStreamUrl took 487 milliseconds Jan 25 18:50:04 rivo volumio[3480]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3508003&eid=292734087&fmt=7&profile=raw&app_id=539451548&cid=2508668&etsp=1769338204&hmac=w-Z2mg0XgDB4LoH1duHuEievTWs Jan 25 18:50:04 rivo volumio[3480]: STREAMING PROXY: Response: 200, length: 104214719 Jan 25 18:50:04 rivo volumio[3480]: info: Jan 25 18:50:04 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:04 rivo kernel: aml_spdif_open Jan 25 18:50:04 rivo volumio[3480]: info: Jan 25 18:50:04 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:04 rivo volumio[3480]: info: Jan 25 18:50:04 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:04 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 25 18:50:04 rivo kernel: set normal 512 fs /4 fs Jan 25 18:50:04 rivo kernel: set spdifout clk:12288000, mpll:49152000 Jan 25 18:50:04 rivo kernel: get spdifout clk:12287994, mpll:49151974 Jan 25 18:50:04 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 25 18:50:04 rivo kernel: set normal 512 fs /4 fs Jan 25 18:50:04 rivo kernel: set spdifout clk:12288000, mpll:49152000 Jan 25 18:50:04 rivo kernel: get spdifout clk:12287994, mpll:49151974 Jan 25 18:50:04 rivo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jan 25 18:50:04 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 25 18:50:04 rivo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jan 25 18:50:04 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand status took 10 milliseconds Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:04 rivo volumio[3480]: info: Jan 25 18:50:04 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand status took 16 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand status took 14 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:04 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:04 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"292734087","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/292734087","trackType":"qobuz"} Jan 25 18:50:04 rivo volumio[3480]: verbose: CURRENT POSITION 0 Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:50:04 rivo volumio[3480]: info: ------------------------------ 23ms Jan 25 18:50:04 rivo volumio[3480]: info: Jan 25 18:50:04 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:04 rivo volumio[3480]: info: Jan 25 18:50:04 rivo volumio[3480]: ---------------------------- MPD announces state update: player Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::getState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand status took 8 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand status took 3 milliseconds Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseState Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:04 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:04 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"292734087","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/292734087","trackType":"qobuz"} Jan 25 18:50:04 rivo volumio[3480]: verbose: CURRENT POSITION 0 Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Jan 25 18:50:04 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:04 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:04 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"292734087","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/292734087","trackType":"qobuz"} Jan 25 18:50:04 rivo volumio[3480]: verbose: CURRENT POSITION 0 Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Jan 25 18:50:04 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: ------------------------------ 81ms Jan 25 18:50:04 rivo volumio[3480]: info: ------------------------------ 80ms Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 56 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 55 milliseconds Jan 25 18:50:04 rivo volumio[3480]: info: sendMpdCommand playlistinfo took 55 milliseconds Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:04 rivo volumio[3480]: verbose: ControllerMpd::parseTrackInfo Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:04 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:04 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"292734087","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/292734087","trackType":"qobuz"} Jan 25 18:50:04 rivo volumio[3480]: verbose: CURRENT POSITION 0 Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Jan 25 18:50:04 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:04 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:04 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"292734087","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/292734087","trackType":"qobuz"} Jan 25 18:50:04 rivo volumio[3480]: verbose: CURRENT POSITION 0 Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Jan 25 18:50:04 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: ControllerMpd::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::servicePushState Jan 25 18:50:04 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:04 rivo volumio[3480]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"292734087","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/292734087","trackType":"qobuz"} Jan 25 18:50:04 rivo volumio[3480]: verbose: CURRENT POSITION 0 Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState stateService play Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::syncState currentStatus play Jan 25 18:50:04 rivo volumio[3480]: info: Received an update from plugin. extracting info from payload Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: CoreStateMachine::pushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioPushState Jan 25 18:50:04 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:50:04 rivo volumio[3480]: info: MRS: Pushing multiroomSync output Jan 25 18:50:04 rivo volumio[3480]: info: ------------------------------ 136ms Jan 25 18:50:04 rivo volumio[3480]: info: ------------------------------ 131ms Jan 25 18:50:04 rivo volumio[3480]: info: ------------------------------ 132ms Jan 25 18:50:04 rivo volumio[3480]: info: Signalling Playback active due to playback status change Jan 25 18:50:04 rivo volumio[3480]: info: MCU Signalled Playback Active Jan 25 18:50:07 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:07 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:07 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:07 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:07 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:07 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:07 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:07 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:07 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:07.403+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1426210 Jan 25 18:50:07 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:07.406+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1426210 Jan 25 18:50:13 rivo volumio[3480]: STREAMING PROXY: Client dropped request, destroying Jan 25 18:50:14 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:14 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:14 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:14 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:14 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:14 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:14 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:14 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:14 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:14.912+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x15004d0 Jan 25 18:50:14 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:14.915+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x15004d0 Jan 25 18:50:22 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:22 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:22 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:22 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:22 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:22 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:22 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:22 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:22 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:22.389+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x16e80f0 Jan 25 18:50:22 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:22.392+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x16e80f0 Jan 25 18:50:29 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:29 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:29 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:29 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:29 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:29 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:29 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:29 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:29 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:29.724+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1426210 Jan 25 18:50:29 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:29.726+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1426210 Jan 25 18:50:37 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:37 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:37 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:37.196+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x16e8708 Jan 25 18:50:37 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:37.198+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x16e8708 Jan 25 18:50:37 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:39 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:39 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:39 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:39 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:39 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:39 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:41 rivo volumio[3480]: info: CorePlayQueue::getTrack 0 Jan 25 18:50:41 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto Jan 25 18:50:41 rivo volumio[3480]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 25 18:50:41 rivo volumio[3480]: info: explodeUri took 455 milliseconds Jan 25 18:50:42 rivo volumio[3480]: info: handleBrowseUri took 638 milliseconds Jan 25 18:50:42 rivo volumio[3480]: info: Executing endpoint getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: Executing endpoint getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: Executing endpoint getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: Executing endpoint getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 25 18:50:42 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:42 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:42 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:42 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:44 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:44 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:44 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:44 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:44 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:44 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:44 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:44 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:44.579+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1500150 Jan 25 18:50:44 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:44.584+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1500150 Jan 25 18:50:44 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:44 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:51 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:51 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:51 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:51 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:51 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:51 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:51 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:51 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:51 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:51.948+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1500268 Jan 25 18:50:51 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:51.950+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1500268 Jan 25 18:50:52 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:50:52 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:50:53 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Jan 25 18:50:53 rivo volumio[3480]: info: CoreCommandRouter::volumioGetBrowseSources Jan 25 18:50:53 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 25 18:50:53 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 25 18:50:55 rivo volumio[3480]: info: searchQOBUZUri took 1510 milliseconds Jan 25 18:50:55 rivo volumio[3480]: info: All search sources collected, pushing search results Jan 25 18:50:55 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 25 18:50:58 rivo volumio[3480]: info: handleBrowseUri took 3269 milliseconds Jan 25 18:50:58 rivo volumio[3480]: info: Preload queue cleared Jan 25 18:50:59 rivo volumio[3480]: info: Received Get System Info Jan 25 18:50:59 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:50:59 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:50:59 rivo volumio[3480]: info: Discovery: Getting this device information Jan 25 18:50:59 rivo volumio[3480]: info: CoreCommandRouter::volumioGetState Jan 25 18:50:59 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:50:59 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 18:50:59 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 18:50:59 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:59.321+09:00 level=INFO msg="BLE descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1500158 Jan 25 18:50:59 rivo volumio5-onboarding[3714]: time=2026-01-25T18:50:59.325+09:00 level=INFO msg="mDNS descriptor updated" deviceId=f148ffbcf1690726227ec7dbbfd34868 deviceName=Rivo deviceModel=0x1500158 Jan 25 18:51:00 rivo volumio[3480]: info: Executing endpoint getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: Executing endpoint getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: Executing endpoint getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: Executing endpoint getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 25 18:51:00 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:51:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:51:00 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:51:00 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:51:01 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:51:01 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:51:01 rivo volumio[3480]: info: Executing endpoint metavolumio Jan 25 18:51:01 rivo volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 18:51:02 rivo volumio[3480]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:51:02 rivo volumio[3480]: TypeError: Cannot read property 'slice' of null Jan 25 18:51:02 rivo volumio[3480]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 25 18:51:02 rivo volumio[3480]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 25 18:51:02 rivo volumio[3480]: at Object.onceWrapper (events.js:520:26) Jan 25 18:51:02 rivo volumio[3480]: at ClientRequest.emit (events.js:400:28) Jan 25 18:51:02 rivo volumio[3480]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Jan 25 18:51:02 rivo volumio[3480]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Jan 25 18:51:02 rivo volumio[3480]: at TLSSocket.socketOnData (_http_client.js:515:22) Jan 25 18:51:02 rivo volumio[3480]: at TLSSocket.emit (events.js:400:28) Jan 25 18:51:02 rivo volumio[3480]: at addChunk (internal/streams/readable.js:293:12) Jan 25 18:51:02 rivo volumio[3480]: at readableAddChunk (internal/streams/readable.js:267:9) Jan 25 18:51:02 rivo volumio[3480]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Jan 25 18:51:02 rivo volumio[3480]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Jan 25 18:51:02 rivo volumio[3480]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:51:03 rivo sudo[17920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-25 18:50 Jan 25 18:51:03 rivo sudo[17920]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 07:07:40 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="90457dc663650a5d2f936402ef6c7dc1"