-- Logs begin at Thu 2025-09-18 11:41:41 +07, end at Thu 2025-09-18 11:46:55 +07. -- Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::stPlaybackTimer Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::servicePause Sep 18 11:45:03 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::servicePause Sep 18 11:45:03 rivoplus volumio[3367]: info: [1758170703018] ControllerQobuz::pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::pause Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand pause Sep 18 11:45:03 rivoplus volumio[3367]: info: Sep 18 11:45:03 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand pause took 3 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:03 rivoplus volumio[3367]: info: Sep 18 11:45:03 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:03 rivoplus volumio[3367]: info: Sep 18 11:45:03 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand status took 7 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand status took 4 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand status took 3 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:03 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:03 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17342,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"257306731","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/257306731","trackType":"qobuz"} Sep 18 11:45:03 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:03 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:03 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::stPlaybackTimer Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:03 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17342,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"257306731","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/257306731","trackType":"qobuz"} Sep 18 11:45:03 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:03 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:03 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::stPlaybackTimer Sep 18 11:45:03 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:03 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17342,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"257306731","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/257306731","trackType":"qobuz"} Sep 18 11:45:03 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus pause Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:03 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:03 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:03 rivoplus volumio[3367]: info: CoreStateMachine::stPlaybackTimer Sep 18 11:45:03 rivoplus volumio[3367]: info: ------------------------------ 102ms Sep 18 11:45:03 rivoplus volumio[3367]: info: ------------------------------ 100ms Sep 18 11:45:03 rivoplus volumio[3367]: info: ------------------------------ 98ms Sep 18 11:45:03 rivoplus volumio[3367]: info: MCU Signalled Playback Inactive Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioClearQueue Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::ClearQueue Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::updateTrackBlock Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrackBlock Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::stPlaybackTimer Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::serviceStop Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::serviceStop Sep 18 11:45:04 rivoplus volumio[3367]: info: [1758170704408] ControllerQobuz::stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::stop Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::clearPlayQueue Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::saveQueue Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushQueue Sep 18 11:45:04 rivoplus volumio[3367]: info: Sep 18 11:45:04 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand stop took 64 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand status took 5 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:04 rivoplus volumio[3367]: info: Sep 18 11:45:04 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:04 rivoplus volumio[3367]: info: Sep 18 11:45:04 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand status took 6 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand status took 4 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:04 rivoplus volumio[3367]: 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":"257306731","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/257306731","trackType":"qobuz"} Sep 18 11:45:04 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: No code Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: ------------------------------ 116ms Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 108 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 109 milliseconds Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:04 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:04 rivoplus volumio[3367]: 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":"257306731","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/257306731","trackType":"qobuz"} Sep 18 11:45:04 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: No code Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:04 rivoplus volumio[3367]: 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":"257306731","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/257306731","trackType":"qobuz"} Sep 18 11:45:04 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus stop Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: No code Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:04 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:04 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:04 rivoplus volumio[3367]: info: ------------------------------ 275ms Sep 18 11:45:04 rivoplus volumio[3367]: info: ------------------------------ 275ms Sep 18 11:45:05 rivoplus kernel: [35B blob data] Sep 18 11:45:05 rivoplus kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 2 Sep 18 11:45:05 rivoplus kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER CONNECTED : 8a:88:5b:4f:26:51 on primary ndev Sep 18 11:45:05 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:05 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:06 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:06 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:06 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:08 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:08 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:08 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:08 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:08 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:08 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:10 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 18 11:45:11 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:11 rivoplus volumio[3367]: info: handleBrowseUri took 624 milliseconds Sep 18 11:45:11 rivoplus volumio[3367]: info: Preload queue cleared Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603244 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603245 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603246 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603247 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603248 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603249 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603250 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603251 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603252 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603253 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603254 Sep 18 11:45:11 rivoplus volumio[3367]: info: Preloading song: qobuz://song/64603255 Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603244 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603245 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603246 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Executing endpoint getSimilarAlbums Sep 18 11:45:11 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Sep 18 11:45:11 rivoplus volumio[3367]: info: Executing endpoint getSimilarAlbums Sep 18 11:45:11 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603247 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:11 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603248 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603249 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603250 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603251 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603252 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603253 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603254 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: Exploding uri qobuz://song/64603255 in service qobuz Sep 18 11:45:11 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:11 rivoplus volumio[3367]: info: explodeUri took 601 milliseconds Sep 18 11:45:11 rivoplus volumio[3367]: info: explodeUri took 702 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 629 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 561 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 635 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 590 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 609 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 646 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 552 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 645 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 645 milliseconds Sep 18 11:45:12 rivoplus volumio[3367]: info: explodeUri took 1059 milliseconds Sep 18 11:45:13 rivoplus volumio[3367]: info: Preload queue cleared Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::ClearQueue Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::stop Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::clearPlayQueue Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::saveQueue Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushQueue Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::addQueueItems Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::addQueueItems Sep 18 11:45:13 rivoplus volumio[3367]: info: Preload queue cleared Sep 18 11:45:13 rivoplus volumio[3367]: info: Adding Item to queue: qobuz://album/qcnls2el8zvsc Sep 18 11:45:13 rivoplus volumio[3367]: info: Exploding uri qobuz://album/qcnls2el8zvsc in service qobuz Sep 18 11:45:13 rivoplus volumio[3367]: https://prod.vlmapi.io/v2/qobuz/explodeUri Sep 18 11:45:13 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:13 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:13 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:13 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:13 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:13 rivoplus volumio[3367]: info: explodeUri took 487 milliseconds Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushQueue Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::saveQueue Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::updateTrackBlock Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::getTrackBlock Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPlay Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::play index 0 Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::stop Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::play index undefined Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreStateMachine::startPlaybackTimer Sep 18 11:45:13 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:13 rivoplus volumio[3367]: info: [1758170713730] ControllerQobuz::clearAddPlayTrack Sep 18 11:45:13 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 11:45:13 rivoplus volumio[3367]: info: sendMpdCommand stop took 6 milliseconds Sep 18 11:45:13 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand clear Sep 18 11:45:13 rivoplus volumio[3367]: info: Sep 18 11:45:13 rivoplus volumio[3367]: ---------------------------- MPD announces system playlist update Sep 18 11:45:13 rivoplus volumio[3367]: info: Ignoring MPD Status Update Sep 18 11:45:13 rivoplus volumio[3367]: info: sendMpdCommand clear took 2 milliseconds Sep 18 11:45:13 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/64603244" Sep 18 11:45:13 rivoplus volumio[3367]: info: Sep 18 11:45:13 rivoplus volumio[3367]: ---------------------------- MPD announces system playlist update Sep 18 11:45:13 rivoplus volumio[3367]: info: Ignoring MPD Status Update Sep 18 11:45:13 rivoplus volumio[3367]: STREAMING PROXY: Handling url /?data=qobuz://song/64603244 Sep 18 11:45:13 rivoplus volumio[3367]: info: Sep 18 11:45:13 rivoplus volumio[3367]: ---------------------------- MPD announces system playlist update Sep 18 11:45:13 rivoplus volumio[3367]: info: Ignoring MPD Status Update Sep 18 11:45:13 rivoplus volumio[3367]: error: updateQueue error: null Sep 18 11:45:13 rivoplus volumio[3367]: info: ------------------------------ 8ms Sep 18 11:45:13 rivoplus volumio[3367]: info: Executing endpoint getStreamUrlqobuz Sep 18 11:45:13 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 18 11:45:14 rivoplus volumio[3367]: info: getStreamUrl took 537 milliseconds Sep 18 11:45:14 rivoplus volumio[3367]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=6124246&eid=64603244&fmt=6&profile=raw&app_id=539451548&cid=3048055&etsp=1758174315&hmac=63LaPn40WKOI0JOnAVuk0B96km4 Sep 18 11:45:14 rivoplus volumio[3367]: STREAMING PROXY: Response: 200, length: 25181871 Sep 18 11:45:14 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/64603244" Sep 18 11:45:14 rivoplus volumio[3367]: error: updateQueue error: null Sep 18 11:45:14 rivoplus volumio[3367]: STREAMING PROXY: Client dropped request, destroying Sep 18 11:45:14 rivoplus volumio[3367]: error: updateQueue error: null Sep 18 11:45:14 rivoplus volumio[3367]: info: ------------------------------ 647ms Sep 18 11:45:14 rivoplus volumio[3367]: info: ------------------------------ 646ms Sep 18 11:45:14 rivoplus volumio[3367]: info: Sep 18 11:45:14 rivoplus volumio[3367]: ---------------------------- MPD announces system playlist update Sep 18 11:45:14 rivoplus volumio[3367]: info: Ignoring MPD Status Update Sep 18 11:45:14 rivoplus volumio[3367]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/64603244" took 7 milliseconds Sep 18 11:45:14 rivoplus volumio[3367]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 11:45:14 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand play Sep 18 11:45:14 rivoplus volumio[3367]: info: Sep 18 11:45:14 rivoplus volumio[3367]: ---------------------------- MPD announces system playlist update Sep 18 11:45:14 rivoplus volumio[3367]: info: Ignoring MPD Status Update Sep 18 11:45:14 rivoplus volumio[3367]: info: Sep 18 11:45:14 rivoplus volumio[3367]: ---------------------------- MPD announces system playlist update Sep 18 11:45:14 rivoplus volumio[3367]: STREAMING PROXY: Handling url /?data=qobuz://song/64603244 Sep 18 11:45:14 rivoplus volumio[3367]: info: Ignoring MPD Status Update Sep 18 11:45:14 rivoplus volumio[3367]: info: ------------------------------ 8ms Sep 18 11:45:14 rivoplus volumio[3367]: info: sendMpdCommand play took 7 milliseconds Sep 18 11:45:14 rivoplus volumio[3367]: info: ------------------------------ 6ms Sep 18 11:45:14 rivoplus volumio[3367]: info: ------------------------------ 4ms Sep 18 11:45:14 rivoplus volumio[3367]: info: Executing endpoint getStreamUrlqobuz Sep 18 11:45:14 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Sep 18 11:45:14 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:14 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:14 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:14 rivoplus volumio[3367]: info: getStreamUrl took 523 milliseconds Sep 18 11:45:14 rivoplus volumio[3367]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=6124246&eid=64603244&fmt=6&profile=raw&app_id=539451548&cid=3048055&etsp=1758174315&hmac=63LaPn40WKOI0JOnAVuk0B96km4 Sep 18 11:45:15 rivoplus volumio[3367]: STREAMING PROXY: Response: 200, length: 25181871 Sep 18 11:45:15 rivoplus volumio[3367]: info: Sep 18 11:45:15 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:15 rivoplus volumio[3367]: info: Sep 18 11:45:15 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:15 rivoplus volumio[3367]: info: Sep 18 11:45:15 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand status took 23 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:15 rivoplus volumio[3367]: info: Sep 18 11:45:15 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand status took 26 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand status took 36 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:15 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"763 Kbps","isStreaming":false,"title":"64603244","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/64603244","trackType":"qobuz"} Sep 18 11:45:15 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService play Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus stop Sep 18 11:45:15 rivoplus volumio[3367]: info: ------------------------------ 59ms Sep 18 11:45:15 rivoplus volumio[3367]: info: Sep 18 11:45:15 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:15 rivoplus volumio[3367]: info: Sep 18 11:45:15 rivoplus volumio[3367]: ---------------------------- MPD announces state update: player Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::getState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand status Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand status took 36 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand status took 8 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand status took 6 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseState Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:15 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"834 Kbps","isStreaming":false,"title":"64603244","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/64603244","trackType":"qobuz"} Sep 18 11:45:15 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService play Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus play Sep 18 11:45:15 rivoplus volumio[3367]: info: Received an update from plugin. extracting info from payload Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:15 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"834 Kbps","isStreaming":false,"title":"64603244","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/64603244","trackType":"qobuz"} Sep 18 11:45:15 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService play Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus play Sep 18 11:45:15 rivoplus volumio[3367]: info: Received an update from plugin. extracting info from payload Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: ------------------------------ 211ms Sep 18 11:45:15 rivoplus volumio[3367]: info: ------------------------------ 210ms Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 147 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 145 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: info: sendMpdCommand playlistinfo took 145 milliseconds Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:15 rivoplus volumio[3367]: verbose: ControllerMpd::parseTrackInfo Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:15 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"878 Kbps","isStreaming":false,"title":"64603244","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/64603244","trackType":"qobuz"} Sep 18 11:45:15 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService play Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus play Sep 18 11:45:15 rivoplus volumio[3367]: info: Received an update from plugin. extracting info from payload Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:15 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"878 Kbps","isStreaming":false,"title":"64603244","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/64603244","trackType":"qobuz"} Sep 18 11:45:15 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService play Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus play Sep 18 11:45:15 rivoplus volumio[3367]: info: Received an update from plugin. extracting info from payload Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: ControllerMpd::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::servicePushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CorePlayQueue::getTrack 0 Sep 18 11:45:15 rivoplus volumio[3367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"878 Kbps","isStreaming":false,"title":"64603244","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/64603244","trackType":"qobuz"} Sep 18 11:45:15 rivoplus volumio[3367]: verbose: CURRENT POSITION 0 Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState stateService play Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::syncState currentStatus play Sep 18 11:45:15 rivoplus volumio[3367]: info: Received an update from plugin. extracting info from payload Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreStateMachine::pushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioPushState Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output update for this device Sep 18 11:45:15 rivoplus volumio[3367]: info: MRS: Pushing multiroomSync output Sep 18 11:45:15 rivoplus volumio[3367]: info: ------------------------------ 450ms Sep 18 11:45:15 rivoplus volumio[3367]: info: ------------------------------ 422ms Sep 18 11:45:15 rivoplus volumio[3367]: info: ------------------------------ 420ms Sep 18 11:45:15 rivoplus volumio[3367]: info: Signalling Playback active due to playback status change Sep 18 11:45:15 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:15 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:15 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:15 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:15 rivoplus volumio[3367]: info: MCU Signalled Playback Active Sep 18 11:45:16 rivoplus volumio[3367]: STREAMING PROXY: Client dropped request, destroying Sep 18 11:45:17 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:17 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:17 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:17 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:17 rivoplus volumio[3367]: info: Executing endpoint metavolumio Sep 18 11:45:17 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 18 11:45:18 rivoplus ntpd[3430]: Soliciting pool server 115.165.161.155 Sep 18 11:45:19 rivoplus ntpd[3430]: Soliciting pool server 115.165.161.155 Sep 18 11:45:21 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:22 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 11:45:22 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 11:45:22 rivoplus volumio[3367]: info: Discovery: Getting this device information Sep 18 11:45:22 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:22 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 11:45:22 rivoplus ntpd[3430]: Soliciting pool server 115.165.161.155 Sep 18 11:45:22 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:24 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:24 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:24 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:25 rivoplus ntpd[3430]: Soliciting pool server 2401:5b60:0:2::21 Sep 18 11:45:31 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:31 rivoplus volumio[3367]: error: Failed request for metavolumio API Sep 18 11:45:34 rivoplus kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER DISCONNECTED : 8a:88:5b:4f:26:51 on primary ndev Sep 18 11:45:34 rivoplus kernel: [35B blob data] Sep 18 11:45:34 rivoplus kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 3 Sep 18 11:45:52 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 11:45:52 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 11:45:52 rivoplus volumio[3367]: info: Discovery: Getting this device information Sep 18 11:45:52 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:45:52 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 11:46:22 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 11:46:22 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 11:46:22 rivoplus volumio[3367]: info: Discovery: Getting this device information Sep 18 11:46:22 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:46:22 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 11:46:24 rivoplus ntpd[3430]: Soliciting pool server 115.165.161.155 Sep 18 11:46:25 rivoplus ntpd[3430]: Soliciting pool server 115.165.161.155 Sep 18 11:46:28 rivoplus ntpd[3430]: Soliciting pool server 115.165.161.155 Sep 18 11:46:31 rivoplus ntpd[3430]: Soliciting pool server 2401:5b60:0:1::52 Sep 18 11:46:52 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 11:46:52 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 11:46:52 rivoplus volumio[3367]: info: Discovery: Getting this device information Sep 18 11:46:52 rivoplus volumio[3367]: info: CoreCommandRouter::volumioGetState Sep 18 11:46:52 rivoplus volumio[3367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 11:46:54 rivoplus volumio[3367]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 11:46:54 rivoplus volumio[3367]: Error: connect ETIMEDOUT 151.101.194.79:443 Sep 18 11:46:54 rivoplus volumio[3367]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 18 11:46:54 rivoplus volumio[3367]: errno: -110, Sep 18 11:46:54 rivoplus volumio[3367]: code: 'ETIMEDOUT', Sep 18 11:46:54 rivoplus volumio[3367]: syscall: 'connect', Sep 18 11:46:54 rivoplus volumio[3367]: address: '151.101.194.79', Sep 18 11:46:54 rivoplus volumio[3367]: port: 443 Sep 18 11:46:54 rivoplus volumio[3367]: } Sep 18 11:46:54 rivoplus volumio[3367]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 11:46:55 rivoplus sudo[5215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-18 11:45 Sep 18 11:46:55 rivoplus sudo[5215]: 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"