-- Logs begin at Sat 2025-06-21 05:03:33 +08, end at Sat 2025-06-21 19:44:23 +08. -- Jun 21 19:43:06 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 156 Jun 21 19:43:06 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 157 Jun 21 19:43:06 rivoplus volumio[3105]: info: Prefetching next song Jun 21 19:43:06 rivoplus volumio[3105]: info: [1750506186076] ControllerTidal::prefetch Jun 21 19:43:06 rivoplus volumio[3105]: info: Getting stream with soundQuality LOSSLESS Jun 21 19:43:06 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/181541520&soundQuality=LOSSLESS" Jun 21 19:43:06 rivoplus volumio[3105]: STREAMING PROXY: Handling url /?data=tidal://song/181541520&soundQuality=LOSSLESS Jun 21 19:43:06 rivoplus volumio[3105]: info: Jun 21 19:43:06 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:43:06 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:43:06 rivoplus volumio[3105]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/181541520&soundQuality=LOSSLESS" took 6 milliseconds Jun 21 19:43:06 rivoplus volumio[3105]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 19:43:06 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 21 19:43:06 rivoplus volumio[3105]: info: Jun 21 19:43:06 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:43:06 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:43:06 rivoplus volumio[3105]: info: Jun 21 19:43:06 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:43:06 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:43:06 rivoplus volumio[3105]: info: ------------------------------ 11ms Jun 21 19:43:06 rivoplus volumio[3105]: info: sendMpdCommand consume 1 took 8 milliseconds Jun 21 19:43:06 rivoplus volumio[3105]: info: ------------------------------ 8ms Jun 21 19:43:06 rivoplus volumio[3105]: info: ------------------------------ 6ms Jun 21 19:43:06 rivoplus volumio[3105]: info: Executing endpoint getStreamUrltidal Jun 21 19:43:06 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 21 19:43:06 rivoplus volumio[3105]: info: getStreamUrl took 869 milliseconds Jun 21 19:43:06 rivoplus volumio[3105]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRmOTNmMzdlMTM5MmY5NzFmYjE2MzlkNDAyN2RkZDQwNS5tcDQ/0.flac?token=1750509786~NGVhNzQ0YjE2NmUyN2MxMjE4OTBiMmJjNzFhMDQyNGQxNzA4YmY4Ng== Jun 21 19:43:07 rivoplus volumio[3105]: STREAMING PROXY: Response: 200, length: 30286040 Jun 21 19:43:09 rivoplus volumio[3105]: info: Jun 21 19:43:09 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:43:09 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:43:09 rivoplus volumio[3105]: info: Jun 21 19:43:09 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:43:09 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:43:09 rivoplus volumio[3105]: info: Jun 21 19:43:09 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:43:09 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:43:09 rivoplus volumio[3105]: info: Jun 21 19:43:09 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:43:09 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:43:09 rivoplus volumio[3105]: info: Jun 21 19:43:09 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:43:09 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:43:09 rivoplus volumio[3105]: info: Jun 21 19:43:09 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:43:09 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:43:09 rivoplus volumio[3105]: info: ------------------------------ 13ms Jun 21 19:43:09 rivoplus volumio[3105]: info: sendMpdCommand status took 11 milliseconds Jun 21 19:43:09 rivoplus volumio[3105]: info: ------------------------------ 10ms Jun 21 19:43:09 rivoplus volumio[3105]: info: sendMpdCommand status took 8 milliseconds Jun 21 19:43:09 rivoplus volumio[3105]: info: ------------------------------ 7ms Jun 21 19:43:09 rivoplus volumio[3105]: info: sendMpdCommand status took 6 milliseconds Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:43:09 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 21 19:43:09 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 21 19:43:09 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:43:09 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:43:09 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 156 Jun 21 19:43:09 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"814 Kbps","isStreaming":false,"title":"181541520&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/181541520&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:43:09 rivoplus volumio[3105]: verbose: CURRENT POSITION 156 Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:43:09 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:09 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 156 Jun 21 19:43:09 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"814 Kbps","isStreaming":false,"title":"181541520&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/181541520&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:43:09 rivoplus volumio[3105]: verbose: CURRENT POSITION 156 Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:43:09 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:09 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 156 Jun 21 19:43:09 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"814 Kbps","isStreaming":false,"title":"181541520&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/181541520&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:43:09 rivoplus volumio[3105]: verbose: CURRENT POSITION 156 Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:43:09 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:09 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:09 rivoplus volumio[3105]: info: ------------------------------ 75ms Jun 21 19:43:09 rivoplus volumio[3105]: info: ------------------------------ 73ms Jun 21 19:43:09 rivoplus volumio[3105]: info: ------------------------------ 70ms Jun 21 19:43:10 rivoplus volumio[3105]: info: CoreStateMachine::startPlaybackTimer Jun 21 19:43:10 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 157 Jun 21 19:43:10 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:43:10 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 157 Jun 21 19:43:10 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:43:10 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:43:18 rivoplus volumio[3105]: STREAMING PROXY: Client dropped request, destroying Jun 21 19:43:25 rivoplus volumio[3105]: Searching all installed plugins Jun 21 19:43:25 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 19:43:25 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 21 19:43:26 rivoplus volumio[3105]: info: searchTIDALUri took 496 milliseconds Jun 21 19:43:26 rivoplus volumio[3105]: info: search took 498 milliseconds Jun 21 19:43:26 rivoplus volumio[3105]: info: All search sources collected, pushing search results Jun 21 19:43:26 rivoplus volumio[3105]: Searching all installed plugins Jun 21 19:43:26 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 19:43:26 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 21 19:43:27 rivoplus volumio[3105]: info: searchTIDALUri took 1057 milliseconds Jun 21 19:43:27 rivoplus volumio[3105]: info: search took 1058 milliseconds Jun 21 19:43:27 rivoplus volumio[3105]: info: All search sources collected, pushing search results Jun 21 19:43:30 rivoplus volumio[3105]: Searching all installed plugins Jun 21 19:43:30 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 19:43:30 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 21 19:43:30 rivoplus volumio[3105]: info: searchTIDALUri took 472 milliseconds Jun 21 19:43:30 rivoplus volumio[3105]: info: search took 473 milliseconds Jun 21 19:43:30 rivoplus volumio[3105]: info: All search sources collected, pushing search results Jun 21 19:43:31 rivoplus volumio[3105]: Searching all installed plugins Jun 21 19:43:31 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 19:43:31 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 21 19:43:31 rivoplus volumio[3105]: info: searchTIDALUri took 480 milliseconds Jun 21 19:43:31 rivoplus volumio[3105]: info: search took 481 milliseconds Jun 21 19:43:36 rivoplus volumio[3105]: error: Search in plugin tidal timed out Jun 21 19:43:36 rivoplus volumio[3105]: info: All search sources collected, pushing search results Jun 21 19:43:53 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 21 19:43:53 rivoplus volumio[3105]: info: browseTIDALUri took 125 milliseconds Jun 21 19:43:53 rivoplus volumio[3105]: info: Preload queue cleared Jun 21 19:43:54 rivoplus volumio[3105]: info: Preload queue cleared Jun 21 19:44:01 rivoplus volumio[3105]: Searching plugin music_service/tidal Jun 21 19:44:01 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 21 19:44:01 rivoplus volumio[3105]: info: searchTIDALUri took 147 milliseconds Jun 21 19:44:01 rivoplus volumio[3105]: info: search took 149 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::volumioNext Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::next Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::stop Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::stPlaybackTimer Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::updateTrackBlock Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrackBlock Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 157 Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::serviceStop Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 157 Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::serviceStop Jun 21 19:44:07 rivoplus volumio[3105]: info: [1750506247868] ControllerTidal::stop Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 19:44:07 rivoplus volumio[3105]: info: ControllerMpd::stop Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand stop took 60 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::play index undefined Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::startPlaybackTimer Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:07 rivoplus volumio[3105]: info: [1750506247934] ControllerTidal::clearAddPlayTrack Jun 21 19:44:07 rivoplus volumio[3105]: info: Getting stream with soundQuality LOSSLESS Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::updateTrackBlock Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrackBlock Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:07 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand stop took 4 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:07 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:07 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:44:07 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand status took 12 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand clear took 10 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand status took 8 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS" Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:07 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:07 rivoplus volumio[3105]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 21 19:44:07 rivoplus volumio[3105]: info: ------------------------------ 31ms Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:44:07 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:44:07 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:44:07 rivoplus volumio[3105]: error: updateQueue error: null Jun 21 19:44:07 rivoplus volumio[3105]: info: Jun 21 19:44:07 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:44:07 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand status took 37 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: ------------------------------ 36ms Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS" took 32 milliseconds Jun 21 19:44:07 rivoplus volumio[3105]: info: ------------------------------ 12ms Jun 21 19:44:07 rivoplus volumio[3105]: info: ------------------------------ 10ms Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 19:44:07 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand play Jun 21 19:44:07 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:07 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService stop Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus stop Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: No code Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 21 19:44:08 rivoplus volumio[3105]: STREAMING PROXY: Handling url /?data=tidal://song/64490965&soundQuality=LOSSLESS Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 79ms Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 87ms Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:44:08 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces system playlist update Jun 21 19:44:08 rivoplus volumio[3105]: info: Ignoring MPD Status Update Jun 21 19:44:08 rivoplus volumio[3105]: info: Signalling Playback active due to playback status change Jun 21 19:44:08 rivoplus volumio[3105]: info: MCU Signalled Playback Inactive Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 82ms Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand play took 72 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 38ms Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 36ms Jun 21 19:44:08 rivoplus volumio[3105]: info: Executing endpoint getStreamUrltidal Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 21 19:44:08 rivoplus volumio[3105]: info: MCU Signalled Playback Active Jun 21 19:44:08 rivoplus volumio[3105]: info: getStreamUrl took 429 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1NzQ3Y2Y4YTBlOTNhY2QxMmJjZjJlOGZlZjI1OGM2OC5tcDQ/0.flac?token=1750509848~Njk2ZmY3NjY1NjNkZjI2NTE3NDQxMjQ4Zjg5MDA5YTk3M2NiMWI1ZA== Jun 21 19:44:08 rivoplus volumio[3105]: STREAMING PROXY: Response: 200, length: 29901019 Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand status took 17 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand status took 14 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand status took 9 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:08 rivoplus volumio[3105]: info: Jun 21 19:44:08 rivoplus volumio[3105]: ---------------------------- MPD announces state update: player Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::getState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand status Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand status took 33 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"64490965&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus stop Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"64490965&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:44:08 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"779 Kbps","isStreaming":false,"title":"64490965&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:44:08 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 97ms Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 137ms Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 133ms Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand status took 109 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand status took 106 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 100 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseState Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"835 Kbps","isStreaming":false,"title":"64490965&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:44:08 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 188ms Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 75 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: info: sendMpdCommand playlistinfo took 74 milliseconds Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:44:08 rivoplus volumio[3105]: verbose: ControllerMpd::parseTrackInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"816 Kbps","isStreaming":false,"title":"64490965&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:44:08 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: ControllerMpd::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::servicePushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CorePlayQueue::getTrack 158 Jun 21 19:44:08 rivoplus volumio[3105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"816 Kbps","isStreaming":false,"title":"64490965&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64490965&soundQuality=LOSSLESS","trackType":"tidal"} Jun 21 19:44:08 rivoplus volumio[3105]: verbose: CURRENT POSITION 158 Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState stateService play Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::syncState currentStatus play Jun 21 19:44:08 rivoplus volumio[3105]: info: Received an update from plugin. extracting info from payload Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreStateMachine::pushState Jun 21 19:44:08 rivoplus volumio[3105]: info: CoreCommandRouter::volumioPushState Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 243ms Jun 21 19:44:08 rivoplus volumio[3105]: info: ------------------------------ 228ms Jun 21 19:44:10 rivoplus volumio[3105]: STREAMING PROXY: Client dropped request, destroying Jun 21 19:44:12 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 21 19:44:12 rivoplus volumio[3105]: info: browseTIDALUri took 103 milliseconds Jun 21 19:44:12 rivoplus volumio[3105]: info: Preload queue cleared Jun 21 19:44:12 rivoplus volumio[3105]: info: Preload queue cleared Jun 21 19:44:22 rivoplus volumio[3105]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jun 21 19:44:22 rivoplus volumio[3105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 19:44:22 rivoplus volumio[3105]: TypeError: Cannot read property 'then' of undefined Jun 21 19:44:22 rivoplus volumio[3105]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Jun 21 19:44:22 rivoplus volumio[3105]: at Socket.emit (events.js:400:28) Jun 21 19:44:22 rivoplus volumio[3105]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 21 19:44:22 rivoplus volumio[3105]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 21 19:44:22 rivoplus volumio[3105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 19:44:23 rivoplus sudo[28303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 19:43 Jun 21 19:44:23 rivoplus sudo[28303]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"