-- Logs begin at Mon 2025-06-23 20:27:52 CEST, end at Thu 2025-06-26 18:29:15 CEST. -- Jun 26 18:28:03 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 18:28:03 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 18:28:03 rivoplus volumio[3117]: info: Discovery: Getting this device information Jun 26 18:28:03 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:03 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 18:28:20 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:22 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:22 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:22 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:22 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:22 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:22 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioSeek Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::seek Jun 26 18:28:25 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:25 rivoplus volumio[3117]: info: TRACKBLOCK {"album":"Reynaldo Hahn: Le Dieu Bleu","albumUri":"qobuz://album/ars5cn5alinsc","albumart":"https://static.qobuz.com/images/covers/sc/in/ars5cn5alinsc_600.jpg","artist":"Dylan Corlay","artistUri":"qobuz://artist/8580511","audioQuality":"HI_RES","duration":178,"explicit":false,"name":"Le dieu bleu: Prélude","service":"qobuz","title":"Le dieu bleu: Prélude","tracknumber":1,"type":"track","uri":"qobuz://song/313361569","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::startPlaybackTimer Jun 26 18:28:25 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:25 rivoplus volumio[3117]: info: [1750955305171] ControllerQobuz::seek Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 26 18:28:25 rivoplus volumio[3117]: info: ControllerMpd::seek Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:25 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:25 rivoplus volumio[3117]: error: null Jun 26 18:28:25 rivoplus volumio[3117]: info: Jun 26 18:28:25 rivoplus volumio[3117]: ---------------------------- MPD announces state update: player Jun 26 18:28:25 rivoplus volumio[3117]: info: ControllerMpd::getState Jun 26 18:28:25 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 26 18:28:25 rivoplus volumio[3117]: info: sendMpdCommand status took 3 milliseconds Jun 26 18:28:25 rivoplus volumio[3117]: verbose: ControllerMpd::parseState Jun 26 18:28:25 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 18:28:25 rivoplus volumio[3117]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 26 18:28:25 rivoplus volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 26 18:28:25 rivoplus volumio[3117]: info: ControllerMpd::pushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::servicePushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:25 rivoplus volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114244,"duration":178,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2526 Kbps","isStreaming":false,"title":"313361569","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/313361569","trackType":"qobuz"} Jun 26 18:28:25 rivoplus volumio[3117]: verbose: CURRENT POSITION 0 Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 26 18:28:25 rivoplus volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:25 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:25 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:25 rivoplus volumio[3117]: info: ------------------------------ 52ms Jun 26 18:28:25 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:25 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:32 rivoplus volumio[3117]: info: Preload queue cleared Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::ClearQueue Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::stop Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::stPlaybackTimer Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::updateTrackBlock Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::getTrackBlock Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::serviceStop Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::serviceStop Jun 26 18:28:32 rivoplus volumio[3117]: info: [1750955312771] ControllerQobuz::stop Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 26 18:28:32 rivoplus volumio[3117]: info: ControllerMpd::stop Jun 26 18:28:32 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::clearPlayQueue Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::saveQueue Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushQueue Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::addQueueItems Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::addQueueItems Jun 26 18:28:32 rivoplus volumio[3117]: info: Preload queue cleared Jun 26 18:28:32 rivoplus volumio[3117]: info: Adding Item to queue: qobuz://album/i9t747uthhvvc Jun 26 18:28:32 rivoplus volumio[3117]: info: Exploding uri qobuz://album/i9t747uthhvvc in service qobuz Jun 26 18:28:32 rivoplus volumio[3117]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 26 18:28:32 rivoplus volumio[3117]: info: Jun 26 18:28:32 rivoplus volumio[3117]: ---------------------------- MPD announces state update: player Jun 26 18:28:32 rivoplus volumio[3117]: info: sendMpdCommand stop took 65 milliseconds Jun 26 18:28:32 rivoplus volumio[3117]: info: ControllerMpd::getState Jun 26 18:28:32 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 26 18:28:32 rivoplus volumio[3117]: info: sendMpdCommand status took 1 milliseconds Jun 26 18:28:32 rivoplus volumio[3117]: verbose: ControllerMpd::parseState Jun 26 18:28:32 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 18:28:32 rivoplus volumio[3117]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 26 18:28:32 rivoplus volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 26 18:28:32 rivoplus volumio[3117]: info: ControllerMpd::pushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::servicePushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:32 rivoplus volumio[3117]: 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":"313361569","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/313361569","trackType":"qobuz"} Jun 26 18:28:32 rivoplus volumio[3117]: verbose: CURRENT POSITION 0 Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::syncState stateService stop Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::syncState currentStatus stop Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: No code Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:32 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:32 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:32 rivoplus volumio[3117]: info: ------------------------------ 77ms Jun 26 18:28:32 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:32 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:32 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:32 rivoplus volumio[3117]: info: MCU Signalled Playback Inactive Jun 26 18:28:32 rivoplus volumio[3117]: info: MCU Signalled Playback Active Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 18:28:33 rivoplus volumio[3117]: info: Discovery: Getting this device information Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 18:28:33 rivoplus volumio[3117]: info: explodeUri took 696 milliseconds Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushQueue Jun 26 18:28:33 rivoplus volumio[3117]: info: CorePlayQueue::saveQueue Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::updateTrackBlock Jun 26 18:28:33 rivoplus volumio[3117]: info: CorePlayQueue::getTrackBlock Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPlay Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::play index 0 Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::stop Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::play index undefined Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 18:28:33 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreStateMachine::startPlaybackTimer Jun 26 18:28:33 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:33 rivoplus volumio[3117]: info: [1750955313484] ControllerQobuz::clearAddPlayTrack Jun 26 18:28:33 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 18:28:33 rivoplus volumio[3117]: info: sendMpdCommand stop took 5 milliseconds Jun 26 18:28:33 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand clear Jun 26 18:28:33 rivoplus volumio[3117]: info: Jun 26 18:28:33 rivoplus volumio[3117]: ---------------------------- MPD announces system playlist update Jun 26 18:28:33 rivoplus volumio[3117]: info: Ignoring MPD Status Update Jun 26 18:28:33 rivoplus volumio[3117]: info: sendMpdCommand clear took 3 milliseconds Jun 26 18:28:33 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/306979039" Jun 26 18:28:33 rivoplus volumio[3117]: error: updateQueue error: null Jun 26 18:28:33 rivoplus volumio[3117]: STREAMING PROXY: Handling url /?data=qobuz://song/306979039 Jun 26 18:28:33 rivoplus volumio[3117]: info: ------------------------------ 4ms Jun 26 18:28:33 rivoplus volumio[3117]: info: Executing endpoint getStreamUrlqobuz Jun 26 18:28:33 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 26 18:28:33 rivoplus volumio[3117]: info: getStreamUrl took 402 milliseconds Jun 26 18:28:33 rivoplus volumio[3117]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1028972&eid=306979039&fmt=27&profile=raw&app_id=539451548&cid=2773576&etsp=1750958913&hmac=v3FgjzAxwjLY7iqfX8qRQacVPgE Jun 26 18:28:34 rivoplus volumio[3117]: STREAMING PROXY: Response: 200, length: 214334168 Jun 26 18:28:34 rivoplus volumio[3117]: STREAMING PROXY: Client dropped request, destroying Jun 26 18:28:34 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/306979039" Jun 26 18:28:34 rivoplus volumio[3117]: info: Jun 26 18:28:34 rivoplus volumio[3117]: ---------------------------- MPD announces system playlist update Jun 26 18:28:34 rivoplus volumio[3117]: info: Ignoring MPD Status Update Jun 26 18:28:34 rivoplus volumio[3117]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/306979039" took 3 milliseconds Jun 26 18:28:34 rivoplus volumio[3117]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 26 18:28:34 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand play Jun 26 18:28:34 rivoplus volumio[3117]: info: ------------------------------ 3ms Jun 26 18:28:34 rivoplus volumio[3117]: info: sendMpdCommand play took 2 milliseconds Jun 26 18:28:34 rivoplus volumio[3117]: STREAMING PROXY: Handling url /?data=qobuz://song/306979039 Jun 26 18:28:34 rivoplus volumio[3117]: info: Executing endpoint getStreamUrlqobuz Jun 26 18:28:34 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 26 18:28:35 rivoplus volumio[3117]: info: getStreamUrl took 413 milliseconds Jun 26 18:28:35 rivoplus volumio[3117]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1028972&eid=306979039&fmt=27&profile=raw&app_id=539451548&cid=2773576&etsp=1750958915&hmac=z1DJpXIbajtI43R9mPCdT2-j4T0 Jun 26 18:28:35 rivoplus volumio[3117]: STREAMING PROXY: Response: 200, length: 214334168 Jun 26 18:28:35 rivoplus volumio[3117]: info: Jun 26 18:28:35 rivoplus volumio[3117]: ---------------------------- MPD announces state update: player Jun 26 18:28:35 rivoplus volumio[3117]: info: ControllerMpd::getState Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 26 18:28:35 rivoplus volumio[3117]: info: Jun 26 18:28:35 rivoplus volumio[3117]: ---------------------------- MPD announces state update: player Jun 26 18:28:35 rivoplus volumio[3117]: info: sendMpdCommand status took 100 milliseconds Jun 26 18:28:35 rivoplus volumio[3117]: info: ControllerMpd::getState Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand status Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::parseState Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 18:28:35 rivoplus volumio[3117]: info: sendMpdCommand status took 3 milliseconds Jun 26 18:28:35 rivoplus volumio[3117]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::parseState Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 26 18:28:35 rivoplus volumio[3117]: info: ControllerMpd::pushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::servicePushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:35 rivoplus volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":338,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"306979039","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/306979039","trackType":"qobuz"} Jun 26 18:28:35 rivoplus volumio[3117]: verbose: CURRENT POSITION 0 Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreStateMachine::syncState currentStatus stop Jun 26 18:28:35 rivoplus volumio[3117]: info: ------------------------------ 113ms Jun 26 18:28:35 rivoplus volumio[3117]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 26 18:28:35 rivoplus volumio[3117]: verbose: ControllerMpd::parseTrackInfo Jun 26 18:28:35 rivoplus volumio[3117]: info: ControllerMpd::pushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::servicePushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CorePlayQueue::getTrack 0 Jun 26 18:28:35 rivoplus volumio[3117]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":338,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4016 Kbps","isStreaming":false,"title":"306979039","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/306979039","trackType":"qobuz"} Jun 26 18:28:35 rivoplus volumio[3117]: verbose: CURRENT POSITION 0 Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreStateMachine::syncState stateService play Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreStateMachine::syncState currentStatus play Jun 26 18:28:35 rivoplus volumio[3117]: info: Received an update from plugin. extracting info from payload Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreStateMachine::pushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::volumioPushState Jun 26 18:28:35 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output update for this device Jun 26 18:28:35 rivoplus volumio[3117]: info: MRS: Pushing multiroomSync output Jun 26 18:28:35 rivoplus volumio[3117]: info: ------------------------------ 79ms Jun 26 18:28:35 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:35 rivoplus volumio[3117]: info: Signalling Playback active due to playback status change Jun 26 18:28:37 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:37 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:37 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:37 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:37 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:37 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:41 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:28:42 rivoplus volumio[3117]: error: Failed request for metavolumio API Jun 26 18:28:43 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:43 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:43 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:43 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:43 rivoplus volumio[3117]: info: Executing endpoint metavolumio Jun 26 18:28:43 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 18:28:54 rivoplus volumio[3117]: info: Retrieving Cloud Streaming UI Jun 26 18:28:54 rivoplus volumio[3117]: info: Getting Tidal Cloud Configuration Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 18:28:54 rivoplus volumio[3117]: info: Getting Qobuz Cloud Configuration Jun 26 18:28:54 rivoplus volumio[3117]: info: Asking plugin for UI Config Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 18:28:54 rivoplus volumio[3117]: info: Getting Spotify Cloud Configuration Jun 26 18:28:54 rivoplus volumio[3117]: info: Asking plugin for UI Config Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 18:28:54 rivoplus volumio[3117]: info: Saving Spotify Acccount Jun 26 18:28:54 rivoplus volumio[3117]: info: Got it Jun 26 18:28:54 rivoplus volumio[3117]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 26 18:28:54 rivoplus volumio[3117]: info: Got Tidal Cloud Configuration Jun 26 18:28:54 rivoplus volumio[3117]: info: Got it Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetBrowseSources Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetBrowseSources Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetBrowseSources Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 18:28:54 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 26 18:28:58 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 18:29:02 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 18:29:03 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 18:29:03 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 18:29:03 rivoplus volumio[3117]: info: Discovery: Getting this device information Jun 26 18:29:03 rivoplus volumio[3117]: info: CoreCommandRouter::volumioGetState Jun 26 18:29:03 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 18:29:05 rivoplus volumio[3117]: info: Disabling MyMusic plugin upnp Jun 26 18:29:05 rivoplus sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 26 18:29:05 rivoplus sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 18:29:05 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 26 18:29:05 rivoplus volumio[3117]: error: Upnp client error: Error: This socket has been ended by the other party Jun 26 18:29:06 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 18:29:10 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 18:29:14 rivoplus volumio[3117]: info: Enabling MyMusic plugin upnp Jun 26 18:29:14 rivoplus volumio[3117]: info: Enabling plugin upnp Jun 26 18:29:14 rivoplus volumio[3117]: info: Loading plugin "upnp"... Jun 26 18:29:14 rivoplus volumio[3117]: info: [1750955354240] Starting Upmpd Daemon Jun 26 18:29:14 rivoplus volumio[3117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 18:29:14 rivoplus volumio[3117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 18:29:14 rivoplus volumio[3117]: Error: listen EADDRINUSE: address already in use :::6599 Jun 26 18:29:14 rivoplus volumio[3117]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jun 26 18:29:14 rivoplus volumio[3117]: at listenInCluster (net.js:1379:12) Jun 26 18:29:14 rivoplus volumio[3117]: at Server.listen (net.js:1465:7) Jun 26 18:29:14 rivoplus volumio[3117]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 26 18:29:14 rivoplus volumio[3117]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 26 18:29:14 rivoplus volumio[3117]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 26 18:29:14 rivoplus volumio[3117]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 26 18:29:14 rivoplus volumio[3117]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jun 26 18:29:14 rivoplus volumio[3117]: code: 'EADDRINUSE', Jun 26 18:29:14 rivoplus volumio[3117]: errno: -98, Jun 26 18:29:14 rivoplus volumio[3117]: syscall: 'listen', Jun 26 18:29:14 rivoplus volumio[3117]: address: '::', Jun 26 18:29:14 rivoplus volumio[3117]: port: 6599 Jun 26 18:29:14 rivoplus volumio[3117]: } Jun 26 18:29:14 rivoplus volumio[3117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 18:29:15 rivoplus sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 18:28 Jun 26 18:29:15 rivoplus sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"