-- Logs begin at Fri 2026-08-28 18:04:48 ACST, end at Sun 2026-08-30 17:11:08 ACST. -- Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:10:02 rivoplus volumio[3291]: No protocol specified Aug 30 17:10:02 rivoplus volumio[3291]: xcb_connection_has_error() returned true Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 17:10:02 rivoplus volumio[3291]: No protocol specified Aug 30 17:10:02 rivoplus volumio[3291]: xcb_connection_has_error() returned true Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:10:02 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 17:10:04 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:04.199+09:30 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" latency=32.683227ms timeout=10s endpoint=http://plugins.volumio.org duration=9.996339795s error="Head \"http://plugins.volumio.org\": context deadline exceeded" Aug 30 17:10:04 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:04.200+09:30 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" latency=32.683227ms timeout=10s endpoint=http://cddb.volumio.org duration=9.997049088s error="Head \"http://cddb.volumio.org\": context deadline exceeded" Aug 30 17:10:25 rivoplus volumio[3291]: info: Preload queue cleared Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::ClearQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::stop Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::pushState Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 6 Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 6 Aug 30 17:10:25 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Aug 30 17:10:25 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::serviceStop Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 6 Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::serviceStop Aug 30 17:10:25 rivoplus volumio[3291]: info: [1788075625717] ControllerTidal::stop Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 17:10:25 rivoplus volumio[3291]: info: ControllerMpd::stop Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::clearPlayQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::addQueueItems Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::addQueueItems Aug 30 17:10:25 rivoplus volumio[3291]: info: Preload queue cleared Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/34387980 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/34387980 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/35030264 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/35030264 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/34387979 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/34387979 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/34387981 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/34387981 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/518049 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/518049 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/395271427 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/395271427 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/58388383 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/58388383 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/177461466 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/177461466 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/149155464 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/149155464 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/148827430 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/148827430 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/149155465 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/149155465 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/149155467 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/149155467 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/147622772 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/147622772 Aug 30 17:10:25 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:25.735+09:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" state=STATUS_STOPPED positionMs=0 volume=40 Aug 30 17:10:25 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:25.737+09:30 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" id=tidal://song/58388383 title="Spirit Of Peace" Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPlay Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::play index 12 Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::addQueueItems Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::addQueueItems Aug 30 17:10:25 rivoplus volumio[3291]: info: Preload queue cleared Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/147622773 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/147622773 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/147622774 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/147622774 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/147622782 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/147622782 Aug 30 17:10:25 rivoplus volumio[3291]: info: Adding Item to queue: tidal://song/147622783 Aug 30 17:10:25 rivoplus volumio[3291]: info: Using cached record of: tidal://song/147622783 Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::stop Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::play index undefined Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 12 Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::startPlaybackTimer Aug 30 17:10:25 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 12 Aug 30 17:10:25 rivoplus volumio[3291]: info: [1788075625793] ControllerTidal::clearAddPlayTrack Aug 30 17:10:25 rivoplus volumio[3291]: info: Getting stream with soundQuality HI_RES Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 17:10:25 rivoplus volumio[3291]: info: Updating RAAT Signal Path Aug 30 17:10:25 rivoplus volumio[3291]: info: Aug 30 17:10:25 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand stop took 102 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: info: ControllerMpd::getState Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand stop took 27 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 17:10:25 rivoplus volumio[3291]: info: Aug 30 17:10:25 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Aug 30 17:10:25 rivoplus volumio[3291]: info: Ignoring MPD Status Update Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand status took 7 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand clear took 6 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/147622772&soundQuality=HI_RES" Aug 30 17:10:25 rivoplus volumio[3291]: error: updateQueue error: null Aug 30 17:10:25 rivoplus volumio[3291]: info: Aug 30 17:10:25 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Aug 30 17:10:25 rivoplus volumio[3291]: info: Ignoring MPD Status Update Aug 30 17:10:25 rivoplus volumio[3291]: info: ------------------------------ 9ms Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/147622772&soundQuality=HI_RES" took 4 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 17:10:25 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand play Aug 30 17:10:25 rivoplus volumio[3291]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 17:10:25 rivoplus volumio[3291]: STREAMING PROXY: Handling url /?data=tidal://song/147622772&soundQuality=HI_RES Aug 30 17:10:25 rivoplus volumio[3291]: info: ------------------------------ 28ms Aug 30 17:10:25 rivoplus volumio[3291]: info: ------------------------------ 23ms Aug 30 17:10:25 rivoplus volumio[3291]: info: sendMpdCommand play took 20 milliseconds Aug 30 17:10:25 rivoplus volumio[3291]: info: Executing endpoint getStreamUrltidal Aug 30 17:10:25 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Aug 30 17:10:25 rivoplus volumio[3291]: info: MCU Signalled Playback Inactive Aug 30 17:10:26 rivoplus volumio[3291]: info: getStreamUrl took 628 milliseconds Aug 30 17:10:26 rivoplus volumio[3291]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0Y2M2NGQ3MWZkOTM1OGY4ZTY2MWMxMjRiZWMyNTNlNl82MS5tcDQ/0.flac?token=1788079226~YTJmNDkyZTVlM2JkY2RjNTg0ZGYwMjQ1MmVhYTQwMjBmYjYxZWZhMg== Aug 30 17:10:26 rivoplus volumio[3291]: STREAMING PROXY: Requesting upstream with range: none Aug 30 17:10:26 rivoplus volumio[3291]: STREAMING PROXY: Upstream status code: 200 Aug 30 17:10:26 rivoplus volumio[3291]: info: Aug 30 17:10:26 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Aug 30 17:10:26 rivoplus volumio[3291]: info: ControllerMpd::getState Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:10:26 rivoplus volumio[3291]: info: sendMpdCommand status took 28 milliseconds Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:10:26 rivoplus volumio[3291]: info: Aug 30 17:10:26 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Aug 30 17:10:26 rivoplus volumio[3291]: info: ControllerMpd::getState Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Aug 30 17:10:26 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:10:26 rivoplus volumio[3291]: info: ControllerMpd::pushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 12 Aug 30 17:10:26 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"147622772&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/147622772&soundQuality=HI_RES","trackType":"tidal"} Aug 30 17:10:26 rivoplus volumio[3291]: verbose: CURRENT POSITION 12 Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Aug 30 17:10:26 rivoplus volumio[3291]: info: ------------------------------ 47ms Aug 30 17:10:26 rivoplus volumio[3291]: info: sendMpdCommand status took 12 milliseconds Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 17:10:26 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 17:10:26 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Aug 30 17:10:26 rivoplus volumio[3291]: info: ControllerMpd::pushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 12 Aug 30 17:10:26 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"847 Kbps","isStreaming":false,"title":"147622772&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/147622772&soundQuality=HI_RES","trackType":"tidal"} Aug 30 17:10:26 rivoplus volumio[3291]: verbose: CURRENT POSITION 12 Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Aug 30 17:10:26 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Aug 30 17:10:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Aug 30 17:10:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreStateMachine::pushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Aug 30 17:10:26 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Aug 30 17:10:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Aug 30 17:10:26 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Aug 30 17:10:26 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:26.954+09:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" state=STATUS_PLAYING positionMs=0 volume=40 Aug 30 17:10:26 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:26.955+09:30 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" id=tidal://song/147622772 title="Barefoot Kid" Aug 30 17:10:26 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:26.957+09:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" state=STATUS_PLAYING positionMs=0 volume=40 Aug 30 17:10:26 rivoplus volumio5-onboarding[3794]: time=2026-08-30T17:10:26.958+09:30 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%3b,10.0.0.55:62432 @ 0x34eb620" id=tidal://song/147622772 title="Barefoot Kid" Aug 30 17:10:26 rivoplus volumio[3291]: info: ------------------------------ 70ms Aug 30 17:10:27 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Aug 30 17:10:27 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Aug 30 17:10:27 rivoplus volumio[3291]: info: Updating RAAT Signal Path Aug 30 17:10:27 rivoplus volumio[3291]: info: Updating RAAT Signal Path Aug 30 17:10:27 rivoplus volumio[3291]: info: MCU Signalled Playback Active Aug 30 17:10:29 rivoplus volumio[3291]: info: Executing endpoint metavolumio Aug 30 17:10:29 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 30 17:10:29 rivoplus volumio[3291]: info: Executing endpoint metavolumio Aug 30 17:10:29 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 30 17:10:29 rivoplus volumio[3291]: info: Executing endpoint metavolumio Aug 30 17:10:29 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 30 17:10:40 rivoplus volumio[3291]: STREAMING PROXY: Upstream closed. Received 31605847 bytes Aug 30 17:10:40 rivoplus volumio[3291]: STREAMING PROXY: Client closed connection Aug 30 17:10:42 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:42 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:43 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:43 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:43 rivoplus volumio[3291]: info: searchTIDALUri took 683 milliseconds Aug 30 17:10:43 rivoplus volumio[3291]: info: search took 694 milliseconds Aug 30 17:10:43 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:43 rivoplus volumio[3291]: info: searchTIDALUri took 630 milliseconds Aug 30 17:10:43 rivoplus volumio[3291]: info: search took 633 milliseconds Aug 30 17:10:43 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:47 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:47 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:48 rivoplus volumio[3291]: info: searchTIDALUri took 762 milliseconds Aug 30 17:10:48 rivoplus volumio[3291]: info: search took 764 milliseconds Aug 30 17:10:48 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:49 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:49 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:50 rivoplus volumio[3291]: info: searchTIDALUri took 749 milliseconds Aug 30 17:10:50 rivoplus volumio[3291]: info: search took 751 milliseconds Aug 30 17:10:50 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:50 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:51 rivoplus volumio[3291]: info: searchTIDALUri took 759 milliseconds Aug 30 17:10:51 rivoplus volumio[3291]: info: search took 760 milliseconds Aug 30 17:10:51 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:52 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:52 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:53 rivoplus volumio[3291]: info: searchTIDALUri took 652 milliseconds Aug 30 17:10:53 rivoplus volumio[3291]: info: search took 655 milliseconds Aug 30 17:10:53 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:53 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:54 rivoplus volumio[3291]: info: searchTIDALUri took 703 milliseconds Aug 30 17:10:54 rivoplus volumio[3291]: info: search took 707 milliseconds Aug 30 17:10:54 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:55 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:56 rivoplus volumio[3291]: info: searchTIDALUri took 773 milliseconds Aug 30 17:10:56 rivoplus volumio[3291]: info: search took 775 milliseconds Aug 30 17:10:56 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:10:59 rivoplus volumio[3291]: Searching plugin music_service/tidal Aug 30 17:10:59 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Aug 30 17:10:59 rivoplus volumio[3291]: info: searchTIDALUri took 653 milliseconds Aug 30 17:10:59 rivoplus volumio[3291]: info: search took 655 milliseconds Aug 30 17:10:59 rivoplus volumio[3291]: info: All search sources collected, pushing search results Aug 30 17:11:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Aug 30 17:11:04 rivoplus volumio[3291]: info: browseTIDALUri took 1177 milliseconds Aug 30 17:11:04 rivoplus volumio[3291]: info: Preload queue cleared Aug 30 17:11:04 rivoplus volumio[3291]: info: Preload queue cleared Aug 30 17:11:04 rivoplus volumio[3291]: info: Preload queue cleared Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171763 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171754 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171757 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171760 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171767 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171759 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171756 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171772 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171771 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171773 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171770 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171764 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171769 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171755 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171768 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171766 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171758 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171765 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171762 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/88171761 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/529327437 Aug 30 17:11:04 rivoplus volumio[3291]: info: Preloading song: tidal://song/21364757 Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171763 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Executing endpoint getSimilarArtists Aug 30 17:11:04 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 30 17:11:04 rivoplus volumio[3291]: info: Executing endpoint getSimilarArtists Aug 30 17:11:04 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171754 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171757 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171760 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Executing endpoint metavolumio Aug 30 17:11:04 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 30 17:11:04 rivoplus volumio[3291]: info: Executing endpoint metavolumio Aug 30 17:11:04 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171767 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171759 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171756 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171772 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171771 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171773 in service tidal Aug 30 17:11:04 rivoplus volumio[3291]: info: explodeTIDALUri took 467 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171770 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171764 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 512 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 515 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171769 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 569 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171755 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171768 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 508 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171766 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 539 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 479 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171758 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171765 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 520 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 497 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171762 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/88171761 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 568 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/529327437 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 540 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 490 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: Exploding uri tidal://song/21364757 in service tidal Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 641 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 571 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 577 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 525 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 517 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 539 milliseconds Aug 30 17:11:05 rivoplus volumio[3291]: info: explodeTIDALUri took 511 milliseconds Aug 30 17:11:06 rivoplus volumio[3291]: info: explodeTIDALUri took 554 milliseconds Aug 30 17:11:06 rivoplus volumio[3291]: info: explodeTIDALUri took 525 milliseconds Aug 30 17:11:06 rivoplus volumio[3291]: info: explodeTIDALUri took 534 milliseconds Aug 30 17:11:07 rivoplus volumio[3291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:11:07 rivoplus volumio[3291]: TypeError: Cannot read property 'slice' of null Aug 30 17:11:07 rivoplus volumio[3291]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 30 17:11:07 rivoplus volumio[3291]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 30 17:11:07 rivoplus volumio[3291]: at Object.onceWrapper (events.js:520:26) Aug 30 17:11:07 rivoplus volumio[3291]: at ClientRequest.emit (events.js:400:28) Aug 30 17:11:07 rivoplus volumio[3291]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Aug 30 17:11:07 rivoplus volumio[3291]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Aug 30 17:11:07 rivoplus volumio[3291]: at TLSSocket.socketOnData (_http_client.js:515:22) Aug 30 17:11:07 rivoplus volumio[3291]: at TLSSocket.emit (events.js:400:28) Aug 30 17:11:07 rivoplus volumio[3291]: at addChunk (internal/streams/readable.js:293:12) Aug 30 17:11:07 rivoplus volumio[3291]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 30 17:11:07 rivoplus volumio[3291]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Aug 30 17:11:07 rivoplus volumio[3291]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Aug 30 17:11:07 rivoplus volumio[3291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:11:08 rivoplus sudo[6317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 17:10 Aug 30 17:11:08 rivoplus sudo[6317]: 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"