-- Logs begin at Sat 2025-09-13 16:35:21 +07, end at Sat 2025-09-13 17:48:20 +07. -- Sep 13 17:47:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 17:47:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 17:47:10 rivoplus volumio[3291]: info: Discovery: Getting this device information Sep 13 17:47:10 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 13 17:47:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 17:47:18 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:18 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:19 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:19 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:19 rivoplus volumio[3291]: info: searchTIDALUri took 838 milliseconds Sep 13 17:47:19 rivoplus volumio[3291]: info: search took 839 milliseconds Sep 13 17:47:20 rivoplus volumio[3291]: info: searchTIDALUri took 650 milliseconds Sep 13 17:47:20 rivoplus volumio[3291]: info: search took 650 milliseconds Sep 13 17:47:20 rivoplus volumio[3291]: info: searchQOBUZUri took 1919 milliseconds Sep 13 17:47:20 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:21 rivoplus volumio[3291]: info: searchQOBUZUri took 1976 milliseconds Sep 13 17:47:21 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:23 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:23 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:24 rivoplus volumio[3291]: info: searchTIDALUri took 633 milliseconds Sep 13 17:47:24 rivoplus volumio[3291]: info: search took 634 milliseconds Sep 13 17:47:24 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:24 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:24 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:24 rivoplus volumio[3291]: info: searchTIDALUri took 548 milliseconds Sep 13 17:47:24 rivoplus volumio[3291]: info: search took 552 milliseconds Sep 13 17:47:25 rivoplus volumio[3291]: info: searchQOBUZUri took 1868 milliseconds Sep 13 17:47:25 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:25 rivoplus volumio[3291]: info: searchQOBUZUri took 1159 milliseconds Sep 13 17:47:25 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:27 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:27 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:27 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:28 rivoplus volumio[3291]: info: searchTIDALUri took 708 milliseconds Sep 13 17:47:28 rivoplus volumio[3291]: info: search took 710 milliseconds Sep 13 17:47:28 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:28 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:28 rivoplus volumio[3291]: info: searchQOBUZUri took 1349 milliseconds Sep 13 17:47:28 rivoplus volumio[3291]: info: searchTIDALUri took 654 milliseconds Sep 13 17:47:28 rivoplus volumio[3291]: info: search took 655 milliseconds Sep 13 17:47:29 rivoplus volumio[3291]: info: searchQOBUZUri took 970 milliseconds Sep 13 17:47:30 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:30 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:30 rivoplus volumio[3291]: info: searchTIDALUri took 549 milliseconds Sep 13 17:47:30 rivoplus volumio[3291]: info: search took 551 milliseconds Sep 13 17:47:30 rivoplus volumio[3291]: Searching all installed plugins Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Sep 13 17:47:30 rivoplus volumio[3291]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 13 17:47:30 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Sep 13 17:47:31 rivoplus volumio[3291]: info: searchQOBUZUri took 1131 milliseconds Sep 13 17:47:31 rivoplus volumio[3291]: info: searchTIDALUri took 615 milliseconds Sep 13 17:47:31 rivoplus volumio[3291]: info: search took 616 milliseconds Sep 13 17:47:31 rivoplus volumio[3291]: info: searchQOBUZUri took 948 milliseconds Sep 13 17:47:32 rivoplus volumio[3291]: error: Search in plugin tidal timed out Sep 13 17:47:32 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:33 rivoplus volumio[3291]: error: Search in plugin tidal timed out Sep 13 17:47:33 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:35 rivoplus volumio[3291]: error: Search in plugin tidal timed out Sep 13 17:47:35 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:35 rivoplus volumio[3291]: error: Search in plugin tidal timed out Sep 13 17:47:35 rivoplus volumio[3291]: info: All search sources collected, pushing search results Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 17:47:40 rivoplus volumio[3291]: info: Discovery: Getting this device information Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::servicePause Sep 13 17:47:40 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::servicePause Sep 13 17:47:40 rivoplus volumio[3291]: info: [1757760460259] ControllerQobuz::pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::pause Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand pause Sep 13 17:47:40 rivoplus volumio[3291]: info: Sep 13 17:47:40 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand pause took 4 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:40 rivoplus volumio[3291]: info: Sep 13 17:47:40 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:40 rivoplus volumio[3291]: info: Sep 13 17:47:40 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:40 rivoplus volumio[3291]: info: Sep 13 17:47:40 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand status took 11 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand status took 8 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand status took 6 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand status took 4 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:40 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:40 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":93859,"duration":203,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1708 Kbps","isStreaming":false,"title":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:40 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:40 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":93859,"duration":203,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1708 Kbps","isStreaming":false,"title":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:40 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:40 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":93859,"duration":203,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1708 Kbps","isStreaming":false,"title":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:40 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 13 17:47:40 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:40 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":93859,"duration":203,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1708 Kbps","isStreaming":false,"title":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:40 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus pause Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:40 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:40 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 13 17:47:40 rivoplus volumio[3291]: info: ------------------------------ 113ms Sep 13 17:47:40 rivoplus volumio[3291]: info: ------------------------------ 109ms Sep 13 17:47:40 rivoplus volumio[3291]: info: ------------------------------ 107ms Sep 13 17:47:40 rivoplus volumio[3291]: info: ------------------------------ 106ms Sep 13 17:47:40 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:40 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:40 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:40 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:40 rivoplus volumio[3291]: info: MCU Signalled Playback Inactive Sep 13 17:47:41 rivoplus volumio[3291]: info: Preload queue cleared Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::ClearQueue Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::stPlaybackTimer Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::serviceStop Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::serviceStop Sep 13 17:47:41 rivoplus volumio[3291]: info: [1757760461141] ControllerQobuz::stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::stop Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::clearPlayQueue Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::addQueueItems Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::addQueueItems Sep 13 17:47:41 rivoplus volumio[3291]: info: Preload queue cleared Sep 13 17:47:41 rivoplus volumio[3291]: info: Adding Item to queue: qobuz://song/78887748 Sep 13 17:47:41 rivoplus volumio[3291]: info: Exploding uri qobuz://song/78887748 in service qobuz Sep 13 17:47:41 rivoplus volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Sep 13 17:47:41 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand stop took 41 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:41 rivoplus volumio[3291]: info: Sep 13 17:47:41 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:41 rivoplus volumio[3291]: info: Sep 13 17:47:41 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:41 rivoplus volumio[3291]: info: Sep 13 17:47:41 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand status took 17 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand status took 14 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand status took 12 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand status took 6 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 13 17:47:41 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:41 rivoplus volumio[3291]: 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":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:41 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: No code Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:41 rivoplus volumio[3291]: 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":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:41 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: No code Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:41 rivoplus volumio[3291]: 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":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:41 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: No code Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:41 rivoplus volumio[3291]: 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":"28231586","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28231586","trackType":"qobuz"} Sep 13 17:47:41 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: No code Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:41 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:41 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:41 rivoplus volumio[3291]: info: ------------------------------ 212ms Sep 13 17:47:41 rivoplus volumio[3291]: info: ------------------------------ 211ms Sep 13 17:47:41 rivoplus volumio[3291]: info: ------------------------------ 214ms Sep 13 17:47:41 rivoplus volumio[3291]: info: ------------------------------ 212ms Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:41 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:42 rivoplus volumio[3291]: info: explodeUri took 886 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 13 17:47:42 rivoplus kernel: [35B blob data] Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPlay Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::play index 0 Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::addQueueItems Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::addQueueItems Sep 13 17:47:42 rivoplus volumio[3291]: info: Preload queue cleared Sep 13 17:47:42 rivoplus volumio[3291]: info: Adding Item to queue: qobuz://song/217092453 Sep 13 17:47:42 rivoplus volumio[3291]: info: Exploding uri qobuz://song/217092453 in service qobuz Sep 13 17:47:42 rivoplus volumio[3291]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::stop Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::play index undefined Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::startPlaybackTimer Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 13 17:47:42 rivoplus volumio[3291]: info: [1757760462057] ControllerQobuz::clearAddPlayTrack Sep 13 17:47:42 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 17:47:42 rivoplus kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 2 Sep 13 17:47:42 rivoplus kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER CONNECTED : 8a:88:5b:4f:26:51 on primary ndev Sep 13 17:47:42 rivoplus volumio[3291]: info: sendMpdCommand stop took 11 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: info: sendMpdCommand clear took 4 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/78887748" Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: STREAMING PROXY: Handling url /?data=qobuz://song/78887748 Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: error: updateQueue error: null Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 14ms Sep 13 17:47:42 rivoplus volumio[3291]: info: Executing endpoint getStreamUrlqobuz Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 13 17:47:42 rivoplus volumio[3291]: info: getStreamUrl took 581 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=6124246&eid=78887748&fmt=6&profile=raw&app_id=539451548&cid=3048055&etsp=1757764063&hmac=jjF2UZVZEkLi0mpGAlEil7lh1hY Sep 13 17:47:42 rivoplus volumio[3291]: info: explodeUri took 639 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushQueue Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::saveQueue Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock Sep 13 17:47:42 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock Sep 13 17:47:42 rivoplus volumio[3291]: STREAMING PROXY: Response: 200, length: 29674461 Sep 13 17:47:42 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/78887748" Sep 13 17:47:42 rivoplus volumio[3291]: STREAMING PROXY: Client dropped request, destroying Sep 13 17:47:42 rivoplus volumio[3291]: error: updateQueue error: null Sep 13 17:47:42 rivoplus volumio[3291]: error: updateQueue error: null Sep 13 17:47:42 rivoplus volumio[3291]: error: updateQueue error: null Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 878ms Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 876ms Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 875ms Sep 13 17:47:42 rivoplus volumio[3291]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/78887748" took 5 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 13 17:47:42 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand play Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: STREAMING PROXY: Handling url /?data=qobuz://song/78887748 Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: info: Sep 13 17:47:42 rivoplus volumio[3291]: ---------------------------- MPD announces system playlist update Sep 13 17:47:42 rivoplus volumio[3291]: info: Ignoring MPD Status Update Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 12ms Sep 13 17:47:42 rivoplus volumio[3291]: info: sendMpdCommand play took 9 milliseconds Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 9ms Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 7ms Sep 13 17:47:42 rivoplus volumio[3291]: info: ------------------------------ 5ms Sep 13 17:47:42 rivoplus volumio[3291]: info: Executing endpoint getStreamUrlqobuz Sep 13 17:47:42 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 13 17:47:43 rivoplus volumio[3291]: info: getStreamUrl took 546 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=6124246&eid=78887748&fmt=6&profile=raw&app_id=539451548&cid=3048055&etsp=1757764064&hmac=Nmq4UPKvp9LWp6aztkDjd1MsabQ Sep 13 17:47:43 rivoplus volumio[3291]: STREAMING PROXY: Response: 200, length: 29674461 Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 77 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 81 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 80 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 61 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:43 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:43 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop Sep 13 17:47:43 rivoplus volumio[3291]: info: ------------------------------ 120ms Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: Sep 13 17:47:43 rivoplus volumio[3291]: ---------------------------- MPD announces state update: player Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::getState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand status Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 44 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 32 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 29 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 16 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 13 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand status took 9 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseState Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:43 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"227 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:43 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:43 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:43 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"227 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:43 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:43 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:43 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"227 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:43 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:43 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: ------------------------------ 282ms Sep 13 17:47:43 rivoplus volumio[3291]: info: ------------------------------ 282ms Sep 13 17:47:43 rivoplus volumio[3291]: info: ------------------------------ 282ms Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 158 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 154 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 155 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: info: sendMpdCommand playlistinfo took 154 milliseconds Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: verbose: ControllerMpd::parseTrackInfo Sep 13 17:47:43 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:43 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"487 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:43 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:43 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:43 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:43 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:44 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"487 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:44 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:44 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:44 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"487 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:44 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:44 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: ControllerMpd::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 Sep 13 17:47:44 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":310,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"487 Kbps","isStreaming":false,"title":"78887748","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78887748","trackType":"qobuz"} Sep 13 17:47:44 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService play Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus play Sep 13 17:47:44 rivoplus volumio[3291]: info: Received an update from plugin. extracting info from payload Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreStateMachine::pushState Sep 13 17:47:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output update for this device Sep 13 17:47:44 rivoplus volumio[3291]: info: MRS: Pushing multiroomSync output Sep 13 17:47:44 rivoplus volumio[3291]: info: ------------------------------ 528ms Sep 13 17:47:44 rivoplus volumio[3291]: info: ------------------------------ 496ms Sep 13 17:47:44 rivoplus volumio[3291]: info: ------------------------------ 494ms Sep 13 17:47:44 rivoplus volumio[3291]: info: ------------------------------ 493ms Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Updating RAAT Signal Path Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Signalling Playback active due to playback status change Sep 13 17:47:44 rivoplus volumio[3291]: info: Adding qobuz://song/78887748 to favourites with specific qobuz method Sep 13 17:47:44 rivoplus volumio[3291]: info: MCU Signalled Playback Active Sep 13 17:47:44 rivoplus volumio[3291]: STREAMING PROXY: Client dropped request, destroying Sep 13 17:47:45 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 13 17:47:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 17:47:46 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 13 17:47:46 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 17:47:46 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 13 17:47:46 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 17:47:46 rivoplus ntpd[15082]: Soliciting pool server 115.165.161.155 Sep 13 17:47:48 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 13 17:47:49 rivoplus ntpd[15082]: Soliciting pool server 115.165.161.155 Sep 13 17:47:49 rivoplus ntpd[15082]: Soliciting pool server 115.165.161.155 Sep 13 17:47:50 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 13 17:47:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 17:47:50 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 13 17:47:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 17:47:50 rivoplus volumio[3291]: info: Executing endpoint metavolumio Sep 13 17:47:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 17:47:51 rivoplus ntpd[15082]: Soliciting pool server 2401:5b60:0:1::52 Sep 13 17:47:52 rivoplus volumio[3291]: error: Failed request for metavolumio API Sep 13 17:48:03 rivoplus kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER DISCONNECTED : 8a:88:5b:4f:26:51 on primary ndev Sep 13 17:48:03 rivoplus kernel: [35B blob data] Sep 13 17:48:03 rivoplus kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 3 Sep 13 17:48:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 17:48:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 17:48:10 rivoplus volumio[3291]: info: Discovery: Getting this device information Sep 13 17:48:10 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState Sep 13 17:48:10 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 17:48:19 rivoplus volumio[3291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 17:48:19 rivoplus volumio[3291]: Error: connect ETIMEDOUT 151.101.194.79:443 Sep 13 17:48:19 rivoplus volumio[3291]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 13 17:48:19 rivoplus volumio[3291]: errno: -110, Sep 13 17:48:19 rivoplus volumio[3291]: code: 'ETIMEDOUT', Sep 13 17:48:19 rivoplus volumio[3291]: syscall: 'connect', Sep 13 17:48:19 rivoplus volumio[3291]: address: '151.101.194.79', Sep 13 17:48:19 rivoplus volumio[3291]: port: 443 Sep 13 17:48:19 rivoplus volumio[3291]: } Sep 13 17:48:19 rivoplus volumio[3291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 17:48:20 rivoplus sudo[17073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 17:47 Sep 13 17:48:20 rivoplus sudo[17073]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"