-- Logs begin at Mon 2026-05-25 19:35:10 HKT, end at Tue 2026-05-26 11:45:26 HKT. -- May 26 11:44:01 rivoplus volumio[3110]: Searching plugin music_service/qobuz May 26 11:44:01 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 26 11:44:02 rivoplus volumio[3110]: Searching plugin music_service/qobuz May 26 11:44:02 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 26 11:44:03 rivoplus volumio[3110]: info: searchQOBUZUri took 1719 milliseconds May 26 11:44:03 rivoplus volumio[3110]: info: All search sources collected, pushing search results May 26 11:44:04 rivoplus volumio[3110]: info: searchQOBUZUri took 1641 milliseconds May 26 11:44:04 rivoplus volumio[3110]: info: All search sources collected, pushing search results May 26 11:44:05 rivoplus volumio[3110]: Searching plugin music_service/qobuz May 26 11:44:05 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 26 11:44:06 rivoplus volumio[3110]: Searching plugin music_service/qobuz May 26 11:44:06 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 26 11:44:07 rivoplus volumio[3110]: info: searchQOBUZUri took 2122 milliseconds May 26 11:44:07 rivoplus volumio[3110]: info: All search sources collected, pushing search results May 26 11:44:08 rivoplus volumio[3110]: Searching plugin music_service/qobuz May 26 11:44:08 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 26 11:44:08 rivoplus volumio[3110]: info: searchQOBUZUri took 1619 milliseconds May 26 11:44:08 rivoplus volumio[3110]: info: All search sources collected, pushing search results May 26 11:44:09 rivoplus volumio[3110]: Searching plugin music_service/qobuz May 26 11:44:09 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 26 11:44:10 rivoplus volumio[3110]: info: searchQOBUZUri took 1658 milliseconds May 26 11:44:10 rivoplus volumio[3110]: info: All search sources collected, pushing search results May 26 11:44:10 rivoplus volumio[3110]: info: searchQOBUZUri took 1442 milliseconds May 26 11:44:10 rivoplus volumio[3110]: info: All search sources collected, pushing search results May 26 11:44:23 rivoplus volumio[3110]: info: Preload queue cleared May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioReplaceandPlayItems May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::ClearQueue May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::stPlaybackTimer May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::updateTrackBlock May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrackBlock May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::serviceStop May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::serviceStop May 26 11:44:23 rivoplus volumio[3110]: info: [1779767063707] ControllerQobuz::stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::stop May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::clearPlayQueue May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::saveQueue May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushQueue May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::addQueueItems May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::addQueueItems May 26 11:44:23 rivoplus volumio[3110]: info: Preload queue cleared May 26 11:44:23 rivoplus volumio[3110]: info: Adding Item to queue: qobuz://album/ym94aw6t312nb May 26 11:44:23 rivoplus volumio[3110]: info: Exploding uri qobuz://album/ym94aw6t312nb in service qobuz May 26 11:44:23 rivoplus volumio[3110]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.720+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_STOPPED positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.722+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416266 title=Hoe-Down May 26 11:44:23 rivoplus volumio[3110]: info: May 26 11:44:23 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:23 rivoplus volumio[3110]: info: sendMpdCommand stop took 59 milliseconds May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:23 rivoplus volumio[3110]: info: May 26 11:44:23 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:23 rivoplus volumio[3110]: info: May 26 11:44:23 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:23 rivoplus volumio[3110]: info: sendMpdCommand status took 6 milliseconds May 26 11:44:23 rivoplus volumio[3110]: info: sendMpdCommand status took 4 milliseconds May 26 11:44:23 rivoplus volumio[3110]: info: sendMpdCommand status took 2 milliseconds May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:23 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 4 milliseconds May 26 11:44:23 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 May 26 11:44:23 rivoplus volumio[3110]: 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":"8416266","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8416266","trackType":"qobuz"} May 26 11:44:23 rivoplus volumio[3110]: verbose: CURRENT POSITION 1 May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: No code May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 May 26 11:44:23 rivoplus volumio[3110]: 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":"8416266","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8416266","trackType":"qobuz"} May 26 11:44:23 rivoplus volumio[3110]: verbose: CURRENT POSITION 1 May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: No code May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 May 26 11:44:23 rivoplus volumio[3110]: 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":"8416266","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8416266","trackType":"qobuz"} May 26 11:44:23 rivoplus volumio[3110]: verbose: CURRENT POSITION 1 May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus stop May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio[3110]: info: No code May 26 11:44:23 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:23 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.830+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.831+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.832+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.832+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.833+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.835+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.835+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.836+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.836+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.836+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.836+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.837+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.837+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:23 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:23.838+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/8416269 title="Butch and Butch" May 26 11:44:23 rivoplus volumio[3110]: info: ------------------------------ 72ms May 26 11:44:23 rivoplus volumio[3110]: info: ------------------------------ 70ms May 26 11:44:23 rivoplus volumio[3110]: info: ------------------------------ 69ms May 26 11:44:23 rivoplus volumio[3110]: info: MCU Signalled Playback Inactive May 26 11:44:23 rivoplus volumio[3110]: info: MCU Signalled Playback Active May 26 11:44:24 rivoplus volumio[3110]: info: explodeUri took 805 milliseconds May 26 11:44:24 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushQueue May 26 11:44:24 rivoplus volumio[3110]: info: CorePlayQueue::saveQueue May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::updateTrackBlock May 26 11:44:24 rivoplus volumio[3110]: info: CorePlayQueue::getTrackBlock May 26 11:44:24 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPlay May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::play index 0 May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::stop May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::play index undefined May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 11:44:24 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:24 rivoplus volumio[3110]: info: CoreStateMachine::startPlaybackTimer May 26 11:44:24 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:24 rivoplus volumio[3110]: info: [1779767064541] ControllerQobuz::clearAddPlayTrack May 26 11:44:24 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop May 26 11:44:24 rivoplus volumio[3110]: info: sendMpdCommand stop took 4 milliseconds May 26 11:44:24 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand clear May 26 11:44:24 rivoplus volumio[3110]: info: May 26 11:44:24 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update May 26 11:44:24 rivoplus volumio[3110]: info: Ignoring MPD Status Update May 26 11:44:24 rivoplus volumio[3110]: info: sendMpdCommand clear took 1 milliseconds May 26 11:44:24 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/166763046" May 26 11:44:24 rivoplus volumio[3110]: info: May 26 11:44:24 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update May 26 11:44:24 rivoplus volumio[3110]: info: Ignoring MPD Status Update May 26 11:44:24 rivoplus volumio[3110]: info: May 26 11:44:24 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update May 26 11:44:24 rivoplus volumio[3110]: info: Ignoring MPD Status Update May 26 11:44:24 rivoplus volumio[3110]: STREAMING PROXY: Handling url /?data=qobuz://song/166763046 May 26 11:44:24 rivoplus volumio[3110]: error: updateQueue error: null May 26 11:44:24 rivoplus volumio[3110]: info: ------------------------------ 5ms May 26 11:44:24 rivoplus volumio[3110]: info: Executing endpoint getStreamUrlqobuz May 26 11:44:24 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 26 11:44:25 rivoplus volumio[3110]: info: getStreamUrl took 748 milliseconds May 26 11:44:25 rivoplus volumio[3110]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=12270908&eid=166763046&fmt=7&profile=raw&app_id=539451548&cid=4270855&etsp=1779770665&hmac=A6TiEzjuKfO9AAAJ_wfR-jfLAJE May 26 11:44:25 rivoplus volumio[3110]: STREAMING PROXY: Requesting upstream with range: none May 26 11:44:26 rivoplus volumio[3110]: STREAMING PROXY: Upstream status code: 200 May 26 11:44:26 rivoplus volumio[3110]: STREAMING PROXY: Client closed connection May 26 11:44:26 rivoplus volumio[3110]: STREAMING PROXY: Upstream closed. Received 334 bytes May 26 11:44:26 rivoplus volumio[3110]: error: updateQueue error: null May 26 11:44:26 rivoplus volumio[3110]: STREAMING PROXY: Incomplete transfer. Expected 30419504, got 334 May 26 11:44:26 rivoplus volumio[3110]: STREAMING PROXY: Retrying with range: bytes=334- (attempt 1) May 26 11:44:26 rivoplus volumio[3110]: error: updateQueue error: null May 26 11:44:26 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/166763046" May 26 11:44:26 rivoplus volumio[3110]: info: ------------------------------ 1643ms May 26 11:44:26 rivoplus volumio[3110]: info: ------------------------------ 1642ms May 26 11:44:26 rivoplus volumio[3110]: info: May 26 11:44:26 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update May 26 11:44:26 rivoplus volumio[3110]: info: Ignoring MPD Status Update May 26 11:44:26 rivoplus volumio[3110]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/166763046" took 2 milliseconds May 26 11:44:26 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 11:44:26 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand play May 26 11:44:26 rivoplus volumio[3110]: info: May 26 11:44:26 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update May 26 11:44:26 rivoplus volumio[3110]: info: Ignoring MPD Status Update May 26 11:44:26 rivoplus volumio[3110]: info: May 26 11:44:26 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update May 26 11:44:26 rivoplus volumio[3110]: info: Ignoring MPD Status Update May 26 11:44:26 rivoplus volumio[3110]: STREAMING PROXY: Handling url /?data=qobuz://song/166763046 May 26 11:44:26 rivoplus volumio[3110]: info: ------------------------------ 5ms May 26 11:44:26 rivoplus volumio[3110]: info: sendMpdCommand play took 4 milliseconds May 26 11:44:26 rivoplus volumio[3110]: info: ------------------------------ 4ms May 26 11:44:26 rivoplus volumio[3110]: info: ------------------------------ 3ms May 26 11:44:26 rivoplus volumio[3110]: info: Executing endpoint getStreamUrlqobuz May 26 11:44:26 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 26 11:44:27 rivoplus volumio[3110]: STREAMING PROXY: Resuming with range: bytes=334- May 26 11:44:27 rivoplus volumio[3110]: STREAMING PROXY: Resume upstream status code: 206 May 26 11:44:27 rivoplus volumio[3110]: info: getStreamUrl took 1049 milliseconds May 26 11:44:27 rivoplus volumio[3110]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=12270908&eid=166763046&fmt=7&profile=raw&app_id=539451548&cid=4270855&etsp=1779770667&hmac=x69Pao_JzbwRA36Kzz1Oasl3FrA May 26 11:44:27 rivoplus volumio[3110]: STREAMING PROXY: Requesting upstream with range: none May 26 11:44:27 rivoplus volumio[3110]: STREAMING PROXY: Upstream status code: 200 May 26 11:44:27 rivoplus volumio[3110]: info: May 26 11:44:27 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:27 rivoplus volumio[3110]: info: May 26 11:44:27 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:27 rivoplus volumio[3110]: info: May 26 11:44:27 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand status took 19 milliseconds May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:27 rivoplus volumio[3110]: info: May 26 11:44:27 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:27 rivoplus volumio[3110]: info: May 26 11:44:27 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand status took 22 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand status took 20 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 3 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand status took 3 milliseconds May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:27 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:27 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"166763046","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/166763046","trackType":"qobuz"} May 26 11:44:27 rivoplus volumio[3110]: verbose: CURRENT POSITION 0 May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus stop May 26 11:44:27 rivoplus volumio[3110]: info: ------------------------------ 29ms May 26 11:44:27 rivoplus volumio[3110]: info: May 26 11:44:27 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::getState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand status took 7 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 6 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 5 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand status took 2 milliseconds May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseState May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:27 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:27 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1376 Kbps","isStreaming":false,"title":"166763046","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/166763046","trackType":"qobuz"} May 26 11:44:27 rivoplus volumio[3110]: verbose: CURRENT POSITION 0 May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play May 26 11:44:27 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:27 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:27 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1377 Kbps","isStreaming":false,"title":"166763046","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/166763046","trackType":"qobuz"} May 26 11:44:27 rivoplus volumio[3110]: verbose: CURRENT POSITION 0 May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play May 26 11:44:27 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:27 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:27 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1377 Kbps","isStreaming":false,"title":"166763046","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/166763046","trackType":"qobuz"} May 26 11:44:27 rivoplus volumio[3110]: verbose: CURRENT POSITION 0 May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play May 26 11:44:27 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.368+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.369+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.369+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.369+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.370+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio[3110]: info: ------------------------------ 74ms May 26 11:44:27 rivoplus volumio[3110]: info: ------------------------------ 73ms May 26 11:44:27 rivoplus volumio[3110]: info: ------------------------------ 58ms May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.376+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.377+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.378+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.379+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.380+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 54 milliseconds May 26 11:44:27 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 53 milliseconds May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo May 26 11:44:27 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:27 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:27 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"166763046","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/166763046","trackType":"qobuz"} May 26 11:44:27 rivoplus volumio[3110]: verbose: CURRENT POSITION 0 May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play May 26 11:44:27 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: ControllerMpd::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState May 26 11:44:27 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 0 May 26 11:44:27 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"166763046","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/166763046","trackType":"qobuz"} May 26 11:44:27 rivoplus volumio[3110]: verbose: CURRENT POSITION 0 May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play May 26 11:44:27 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio[3110]: info: CoreStateMachine::pushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState May 26 11:44:27 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.437+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.439+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.444+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.445+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.445+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.445+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" state=STATUS_PLAYING positionMs=0 volume=98 May 26 11:44:27 rivoplus volumio5-onboarding[3963]: time=2026-05-26T11:44:27.446+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.57:49285,00:00:00:00:00:00%04 @ 0x260e9c0" id=qobuz://song/166763046 title="Cognitive Flow" May 26 11:44:27 rivoplus volumio[3110]: info: ------------------------------ 128ms May 26 11:44:27 rivoplus volumio[3110]: info: ------------------------------ 123ms May 26 11:44:32 rivoplus volumio[3110]: STREAMING PROXY: Upstream closed. Received 30419504 bytes May 26 11:44:32 rivoplus volumio[3110]: STREAMING PROXY: Client closed connection May 26 11:44:32 rivoplus volumio[3110]: STREAMING PROXY: Resume ended. Received 30419170 bytes May 26 11:45:25 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch May 26 11:45:25 rivoplus volumio[3110]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 11:45:25 rivoplus volumio[3110]: TypeError: Cannot read property 'then' of undefined May 26 11:45:25 rivoplus volumio[3110]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) May 26 11:45:25 rivoplus volumio[3110]: at Socket.emit (events.js:400:28) May 26 11:45:25 rivoplus volumio[3110]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 26 11:45:25 rivoplus volumio[3110]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 26 11:45:25 rivoplus volumio[3110]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 11:45:26 rivoplus sudo[20016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-26 11:44 May 26 11:45:26 rivoplus sudo[20016]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"