-- Logs begin at Mon 2026-02-23 22:49:07 CET, end at Tue 2026-02-24 19:28:13 CET. -- Feb 24 19:27:08 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreCommandRouter::volumioNext Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::next Feb 24 19:27:11 rivoplus volumio[3110]: info: [1771957631016] hiResAudio::next Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::next Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::stop Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::stPlaybackTimer Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::updateTrackBlock Feb 24 19:27:11 rivoplus volumio[3110]: info: CorePlayQueue::getTrackBlock Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:11 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::serviceStop Feb 24 19:27:11 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 1 Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreCommandRouter::serviceStop Feb 24 19:27:11 rivoplus volumio[3110]: info: [1771957631023] hiResAudio::stop Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 19:27:11 rivoplus volumio[3110]: info: ControllerMpd::stop Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand stop took 56 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::play index undefined Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 19:27:11 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::startPlaybackTimer Feb 24 19:27:11 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::updateTrackBlock Feb 24 19:27:11 rivoplus volumio[3110]: info: CorePlayQueue::getTrackBlock Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:11 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:11 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:11 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand stop took 7 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand status took 5 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand status took 4 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:11 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand status took 7 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand clear took 6 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f" Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:11 rivoplus volumio[3110]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 19:27:11 rivoplus volumio[3110]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Handling url /?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 18ms Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 19ms Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:11 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:11 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:11 rivoplus volumio[3110]: error: updateQueue error: null Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 18ms Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:11 rivoplus volumio[3110]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 27ms Feb 24 19:27:11 rivoplus volumio[3110]: info: Executing endpoint getStreamUrlhi_res_audio Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: hi_res_audio , getStreamUrlAPI Feb 24 19:27:11 rivoplus volumio[3110]: info: MCU Signalled Playback Inactive Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Got real url: https://highresaudiomusic.akamaized.net//2021/01/26/xhsspb-iwouldntch-format-fl192.flac?__token__=st=1771957631~exp=1771963155~acl=%2F%2A~hmac=9d83238a5290f0699116c0f5b0f024b776b63f829170594af4c008b692aa2a10&info=USERdd959e18-6ea0-4223-8e3a-afc0be832c38ALBUMeed850e7-7fa5-480e-be13-4222593e893fTRACKa703c1ef-64c7-46ff-aa45-07bfdd70e1bd Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Requesting upstream with range: none Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Upstream status code: 200 Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Client closed connection Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f" Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Upstream closed. Received 13878 bytes Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Incomplete transfer. Expected 66391963, got 13878 Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Retrying with range: bytes=13878- (attempt 1) Feb 24 19:27:11 rivoplus volumio[3110]: error: updateQueue error: null Feb 24 19:27:11 rivoplus volumio[3110]: error: updateQueue error: null Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 719ms Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 718ms Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:11 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand add "http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f" took 4 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 19:27:11 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand play Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:11 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:11 rivoplus volumio[3110]: info: Feb 24 19:27:11 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:11 rivoplus volumio[3110]: STREAMING PROXY: Handling url /?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f Feb 24 19:27:11 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 8ms Feb 24 19:27:11 rivoplus volumio[3110]: info: sendMpdCommand play took 6 milliseconds Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 5ms Feb 24 19:27:11 rivoplus volumio[3110]: info: ------------------------------ 5ms Feb 24 19:27:11 rivoplus volumio[3110]: info: Executing endpoint getStreamUrlhi_res_audio Feb 24 19:27:11 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: hi_res_audio , getStreamUrlAPI Feb 24 19:27:12 rivoplus volumio[3110]: STREAMING PROXY: Got real url: https://highresaudiomusic.akamaized.net//2021/01/26/xhsspb-iwouldntch-format-fl192.flac?__token__=st=1771957631~exp=1771963155~acl=%2F%2A~hmac=9d83238a5290f0699116c0f5b0f024b776b63f829170594af4c008b692aa2a10&info=USERdd959e18-6ea0-4223-8e3a-afc0be832c38ALBUMeed850e7-7fa5-480e-be13-4222593e893fTRACKa703c1ef-64c7-46ff-aa45-07bfdd70e1bd Feb 24 19:27:12 rivoplus volumio[3110]: STREAMING PROXY: Requesting upstream with range: none Feb 24 19:27:12 rivoplus volumio[3110]: STREAMING PROXY: Upstream status code: 200 Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 22 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 28 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 27 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 9 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus stop Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 60ms Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:12 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 46 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 46 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 60 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 38 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 27 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 194ms Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 195ms Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 180ms Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:12 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces system playlist update Feb 24 19:27:12 rivoplus volumio[3110]: info: Ignoring MPD Status Update Feb 24 19:27:12 rivoplus volumio[3110]: info: Feb 24 19:27:12 rivoplus volumio[3110]: ---------------------------- MPD announces state update: player Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::getState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand status Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 142ms Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 122 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 22ms Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 19 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 18ms Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand status took 17 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseState Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4198 Kbps","isStreaming":false,"title":"I Wouldn't Change A Thing (Live At The Ahmanson Theatre, 1976)","artist":"Diana Ross","album":"An Evening With Diana Ross (Remastered)","uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4198 Kbps","isStreaming":false,"title":"I Wouldn't Change A Thing (Live At The Ahmanson Theatre, 1976)","artist":"Diana Ross","album":"An Evening With Diana Ross (Remastered)","uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 218ms Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 208ms Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetQueue Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::getQueue Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getQueue Feb 24 19:27:12 rivoplus volumio[3110]: info: Signalling Playback active due to playback status change Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 114 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: info: sendMpdCommand playlistinfo took 111 milliseconds Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: verbose: ControllerMpd::parseTrackInfo Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4198 Kbps","isStreaming":false,"title":"I Wouldn't Change A Thing (Live At The Ahmanson Theatre, 1976)","artist":"Diana Ross","album":"An Evening With Diana Ross (Remastered)","uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4284 Kbps","isStreaming":false,"title":"I Wouldn't Change A Thing (Live At The Ahmanson Theatre, 1976)","artist":"Diana Ross","album":"An Evening With Diana Ross (Remastered)","uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: ControllerMpd::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::servicePushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CorePlayQueue::getTrack 2 Feb 24 19:27:12 rivoplus volumio[3110]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1120,"duration":113,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4284 Kbps","isStreaming":false,"title":"I Wouldn't Change A Thing (Live At The Ahmanson Theatre, 1976)","artist":"Diana Ross","album":"An Evening With Diana Ross (Remastered)","uri":"http://127.0.0.1:3245?data=hi_res_audio://a703c1ef-64c7-46ff-aa45-07bfdd70e1bd_eed850e7-7fa5-480e-be13-4222593e893f","trackType":""} Feb 24 19:27:12 rivoplus volumio[3110]: verbose: CURRENT POSITION 2 Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState stateService play Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::syncState currentStatus play Feb 24 19:27:12 rivoplus volumio[3110]: info: Received an update from plugin. extracting info from payload Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreStateMachine::pushState Feb 24 19:27:12 rivoplus volumio[3110]: info: CoreCommandRouter::volumioPushState Feb 24 19:27:12 rivoplus volumio[3110]: STREAMING PROXY: Resuming with range: bytes=13878- Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 321ms Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 199ms Feb 24 19:27:12 rivoplus volumio[3110]: info: ------------------------------ 196ms Feb 24 19:27:12 rivoplus volumio[3110]: info: MCU Signalled Playback Active Feb 24 19:27:13 rivoplus volumio[3110]: STREAMING PROXY: Resume upstream status code: 206 Feb 24 19:27:18 rivoplus volumio[3110]: STREAMING PROXY: Resume ended. Received 66378085 bytes Feb 24 19:27:18 rivoplus volumio[3110]: STREAMING PROXY: Upstream closed. Received 66391963 bytes Feb 24 19:27:18 rivoplus volumio[3110]: STREAMING PROXY: Client closed connection Feb 24 19:27:47 rivoplus volumio[3110]: info: CoreCommandRouter::volumioGetState Feb 24 19:28:13 rivoplus volumio[3110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Feb 24 19:28:13 rivoplus volumio[3110]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 19:28:13 rivoplus volumio[3110]: TypeError: Cannot read property 'then' of undefined Feb 24 19:28:13 rivoplus volumio[3110]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Feb 24 19:28:13 rivoplus volumio[3110]: at Socket.emit (events.js:400:28) Feb 24 19:28:13 rivoplus volumio[3110]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 24 19:28:13 rivoplus volumio[3110]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 24 19:28:13 rivoplus volumio[3110]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 19:28:13 rivoplus sudo[6880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 19:27 Feb 24 19:28:13 rivoplus sudo[6880]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 02:06:11 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="6fcef4b36db553cbf88901935dbe83e3"