-- Logs begin at Sun 2025-07-06 03:11:00 +08, end at Sun 2025-07-06 14:43:49 +08. -- Jul 06 14:43:07 rivoplus volumio[3162]: info: CoreCommandRouter::volumioGetQueue Jul 06 14:43:07 rivoplus volumio[3162]: info: CoreStateMachine::getQueue Jul 06 14:43:07 rivoplus volumio[3162]: info: CorePlayQueue::getQueue Jul 06 14:43:14 rivoplus volumio[3162]: Searching all installed plugins Jul 06 14:43:14 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 14:43:14 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jul 06 14:43:14 rivoplus volumio[3162]: info: searchTIDALUri took 455 milliseconds Jul 06 14:43:14 rivoplus volumio[3162]: Searching all installed plugins Jul 06 14:43:14 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 14:43:14 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jul 06 14:43:15 rivoplus volumio[3162]: info: searchTIDALUri took 939 milliseconds Jul 06 14:43:15 rivoplus volumio[3162]: info: search took 940 milliseconds Jul 06 14:43:15 rivoplus volumio[3162]: info: All search sources collected, pushing search results Jul 06 14:43:16 rivoplus volumio[3162]: Searching all installed plugins Jul 06 14:43:16 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 14:43:16 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jul 06 14:43:16 rivoplus volumio[3162]: info: searchTIDALUri took 495 milliseconds Jul 06 14:43:16 rivoplus volumio[3162]: info: search took 496 milliseconds Jul 06 14:43:16 rivoplus volumio[3162]: info: All search sources collected, pushing search results Jul 06 14:43:17 rivoplus volumio[3162]: Searching all installed plugins Jul 06 14:43:17 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 14:43:17 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jul 06 14:43:17 rivoplus volumio[3162]: info: searchTIDALUri took 509 milliseconds Jul 06 14:43:17 rivoplus volumio[3162]: info: search took 510 milliseconds Jul 06 14:43:17 rivoplus volumio[3162]: info: All search sources collected, pushing search results Jul 06 14:43:18 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jul 06 14:43:19 rivoplus volumio[3162]: error: Search in plugin tidal timed out Jul 06 14:43:19 rivoplus volumio[3162]: info: All search sources collected, pushing search results Jul 06 14:43:19 rivoplus volumio[3162]: info: browseTIDALUri took 1240 milliseconds Jul 06 14:43:20 rivoplus volumio[3162]: info: Preload queue cleared Jul 06 14:43:20 rivoplus volumio[3162]: info: Preload queue cleared Jul 06 14:43:20 rivoplus volumio[3162]: info: Preload queue cleared Jul 06 14:43:20 rivoplus volumio[3162]: info: Preloading song: tidal://song/156772718 Jul 06 14:43:20 rivoplus volumio[3162]: info: Exploding uri tidal://song/156772718 in service tidal Jul 06 14:43:20 rivoplus volumio[3162]: info: No valid Plugin REST Endpoint: getSimilarArtists Jul 06 14:43:20 rivoplus volumio[3162]: info: explodeTIDALUri took 382 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: Preload queue cleared Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::ClearQueue Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::stPlaybackTimer Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::updateTrackBlock Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrackBlock Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 274 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::serviceStop Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 274 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::serviceStop Jul 06 14:43:25 rivoplus volumio[3162]: info: [1751784205154] ControllerTidal::stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::stop Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::clearPlayQueue Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::saveQueue Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushQueue Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::addQueueItems Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::addQueueItems Jul 06 14:43:25 rivoplus volumio[3162]: info: Preload queue cleared Jul 06 14:43:25 rivoplus volumio[3162]: info: Adding Item to queue: tidal://artist/21513876/156772714 Jul 06 14:43:25 rivoplus volumio[3162]: info: Exploding uri tidal://artist/21513876/156772714 in service tidal Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand stop took 32 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand status took 5 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand status took 4 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand status took 2 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::parseTrackInfo Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::parseTrackInfo Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 274 Jul 06 14:43:25 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225750983&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225750983&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:25 rivoplus volumio[3162]: verbose: CURRENT POSITION 274 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: No code Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 274 Jul 06 14:43:25 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225750983&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225750983&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:25 rivoplus volumio[3162]: verbose: CURRENT POSITION 274 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: No code Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 274 Jul 06 14:43:25 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225750983&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225750983&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:25 rivoplus volumio[3162]: verbose: CURRENT POSITION 274 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: No code Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 96ms Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 95ms Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 95ms Jul 06 14:43:25 rivoplus volumio[3162]: info: Signalling Playback active due to playback status change Jul 06 14:43:25 rivoplus volumio[3162]: info: MCU Signalled Playback Inactive Jul 06 14:43:25 rivoplus volumio[3162]: info: MCU Signalled Playback Active Jul 06 14:43:25 rivoplus volumio[3162]: info: explodeTIDALUri took 703 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushQueue Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::saveQueue Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::updateTrackBlock Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrackBlock Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPlay Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::play index 0 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::stop Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::play index undefined Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::startPlaybackTimer Jul 06 14:43:25 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:25 rivoplus volumio[3162]: info: [1751784205871] ControllerTidal::clearAddPlayTrack Jul 06 14:43:25 rivoplus volumio[3162]: info: Getting stream with soundQuality LOSSLESS Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand stop took 4 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand clear Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces system playlist update Jul 06 14:43:25 rivoplus volumio[3162]: info: Ignoring MPD Status Update Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand clear took 4 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS" Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces system playlist update Jul 06 14:43:25 rivoplus volumio[3162]: info: Ignoring MPD Status Update Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces system playlist update Jul 06 14:43:25 rivoplus volumio[3162]: info: Ignoring MPD Status Update Jul 06 14:43:25 rivoplus volumio[3162]: error: updateQueue error: null Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces system playlist update Jul 06 14:43:25 rivoplus volumio[3162]: info: Ignoring MPD Status Update Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 6ms Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS" took 5 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 5ms Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 4ms Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 14:43:25 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand play Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces system playlist update Jul 06 14:43:25 rivoplus volumio[3162]: info: Ignoring MPD Status Update Jul 06 14:43:25 rivoplus volumio[3162]: info: Jul 06 14:43:25 rivoplus volumio[3162]: ---------------------------- MPD announces system playlist update Jul 06 14:43:25 rivoplus volumio[3162]: info: Ignoring MPD Status Update Jul 06 14:43:25 rivoplus volumio[3162]: STREAMING PROXY: Handling url /?data=tidal://song/156772718&soundQuality=LOSSLESS Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 6ms Jul 06 14:43:25 rivoplus volumio[3162]: info: sendMpdCommand play took 4 milliseconds Jul 06 14:43:25 rivoplus volumio[3162]: info: Executing endpoint getStreamUrltidal Jul 06 14:43:25 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 16ms Jul 06 14:43:25 rivoplus volumio[3162]: info: ------------------------------ 15ms Jul 06 14:43:26 rivoplus volumio[3162]: info: getStreamUrl took 554 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlODI5NDE1MjgxYTVjYzk5YTlhOGU2MTI0NzU1NzQzZF82MS5tcDQ/0.flac?token=1751787806~NDAzNjljYTg2ZDA4NTVmOWEzNzJkZjNjNTQ5MmRiZTllYjBhOTQxOQ== Jul 06 14:43:26 rivoplus volumio[3162]: STREAMING PROXY: Response: 200, length: 8129694 Jul 06 14:43:26 rivoplus volumio[3162]: info: Jul 06 14:43:26 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:26 rivoplus volumio[3162]: info: Jul 06 14:43:26 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:26 rivoplus volumio[3162]: info: Jul 06 14:43:26 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand status took 18 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:26 rivoplus volumio[3162]: info: Jul 06 14:43:26 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand status took 17 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand status took 12 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseTrackInfo Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:26 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"156772718&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:26 rivoplus volumio[3162]: verbose: CURRENT POSITION 0 Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService play Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus stop Jul 06 14:43:26 rivoplus volumio[3162]: info: ------------------------------ 32ms Jul 06 14:43:26 rivoplus volumio[3162]: info: Jul 06 14:43:26 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:26 rivoplus volumio[3162]: info: Jul 06 14:43:26 rivoplus volumio[3162]: ---------------------------- MPD announces state update: player Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::getState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand status Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand status took 12 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand status took 5 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand status took 4 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseTrackInfo Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseTrackInfo Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseState Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:26 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"156772718&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:26 rivoplus volumio[3162]: verbose: CURRENT POSITION 0 Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService play Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus play Jul 06 14:43:26 rivoplus volumio[3162]: info: Received an update from plugin. extracting info from payload Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:26 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"156772718&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:26 rivoplus volumio[3162]: verbose: CURRENT POSITION 0 Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService play Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus play Jul 06 14:43:26 rivoplus volumio[3162]: info: Received an update from plugin. extracting info from payload Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: ------------------------------ 65ms Jul 06 14:43:26 rivoplus volumio[3162]: info: ------------------------------ 62ms Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 06 14:43:26 rivoplus volumio[3162]: verbose: ControllerMpd::parseTrackInfo Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:26 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"581 Kbps","isStreaming":false,"title":"156772718&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:26 rivoplus volumio[3162]: verbose: CURRENT POSITION 0 Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService play Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus play Jul 06 14:43:26 rivoplus volumio[3162]: info: Received an update from plugin. extracting info from payload Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:26 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"507 Kbps","isStreaming":false,"title":"156772718&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:26 rivoplus volumio[3162]: verbose: CURRENT POSITION 0 Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService play Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus play Jul 06 14:43:26 rivoplus volumio[3162]: info: Received an update from plugin. extracting info from payload Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: ControllerMpd::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::servicePushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CorePlayQueue::getTrack 0 Jul 06 14:43:26 rivoplus volumio[3162]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"507 Kbps","isStreaming":false,"title":"156772718&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/156772718&soundQuality=LOSSLESS","trackType":"tidal"} Jul 06 14:43:26 rivoplus volumio[3162]: verbose: CURRENT POSITION 0 Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState stateService play Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::syncState currentStatus play Jul 06 14:43:26 rivoplus volumio[3162]: info: Received an update from plugin. extracting info from payload Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreStateMachine::pushState Jul 06 14:43:26 rivoplus volumio[3162]: info: CoreCommandRouter::volumioPushState Jul 06 14:43:26 rivoplus volumio[3162]: info: ------------------------------ 117ms Jul 06 14:43:26 rivoplus volumio[3162]: info: ------------------------------ 111ms Jul 06 14:43:26 rivoplus volumio[3162]: info: ------------------------------ 118ms Jul 06 14:43:31 rivoplus volumio[3162]: STREAMING PROXY: Client dropped request, destroying Jul 06 14:43:48 rivoplus volumio[3162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jul 06 14:43:48 rivoplus volumio[3162]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 14:43:48 rivoplus volumio[3162]: TypeError: Cannot read property 'then' of undefined Jul 06 14:43:48 rivoplus volumio[3162]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Jul 06 14:43:48 rivoplus volumio[3162]: at Socket.emit (events.js:400:28) Jul 06 14:43:48 rivoplus volumio[3162]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 06 14:43:48 rivoplus volumio[3162]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 06 14:43:48 rivoplus volumio[3162]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 14:43:49 rivoplus sudo[7850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 14:42 Jul 06 14:43:49 rivoplus sudo[7850]: 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"