-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-04-26 11:37:44 CEST. -- Apr 26 11:36:00 rivo volumio[3585]: info: searchQOBUZUri took 738 milliseconds Apr 26 11:36:00 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:36:03 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::ClearQueue Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::stop Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::stPlaybackTimer Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::updateTrackBlock Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::getTrackBlock Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::serviceStop Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::serviceStop Apr 26 11:36:03 rivo volumio[3585]: info: [1745660163863] ControllerQobuz::stop Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::stop Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::clearPlayQueue Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::saveQueue Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushQueue Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::addQueueItems Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::addQueueItems Apr 26 11:36:03 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:36:03 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75927249 Apr 26 11:36:03 rivo volumio[3585]: info: Exploding uri qobuz://song/75927249 in service qobuz Apr 26 11:36:03 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:03 rivo volumio[3585]: info: Apr 26 11:36:03 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand stop took 50 milliseconds Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:03 rivo volumio[3585]: info: Apr 26 11:36:03 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:03 rivo volumio[3585]: info: Apr 26 11:36:03 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:03 rivo volumio[3585]: info: Apr 26 11:36:03 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand status took 15 milliseconds Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand status took 13 milliseconds Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand status took 11 milliseconds Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand status took 9 milliseconds Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 26 11:36:03 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 26 11:36:03 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:03 rivo volumio[3585]: 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":"28015745","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28015745","trackType":"qobuz"} Apr 26 11:36:03 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::syncState stateService stop Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus stop Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:03 rivo volumio[3585]: info: No code Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:03 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:03 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:03 rivo volumio[3585]: 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":"28015745","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28015745","trackType":"qobuz"} Apr 26 11:36:03 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::syncState stateService stop Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus stop Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:03 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:03 rivo volumio[3585]: info: No code Apr 26 11:36:03 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:03 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:04 rivo volumio[3585]: 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":"28015745","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28015745","trackType":"qobuz"} Apr 26 11:36:04 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::syncState stateService stop Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus stop Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: No code Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:04 rivo volumio[3585]: 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":"28015745","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/28015745","trackType":"qobuz"} Apr 26 11:36:04 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::syncState stateService stop Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus stop Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: No code Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:04 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:04 rivo volumio[3585]: info: ------------------------------ 219ms Apr 26 11:36:04 rivo volumio[3585]: info: ------------------------------ 217ms Apr 26 11:36:04 rivo volumio[3585]: info: ------------------------------ 218ms Apr 26 11:36:04 rivo volumio[3585]: info: ------------------------------ 216ms Apr 26 11:36:04 rivo volumio[3585]: info: Signalling Playback active due to playback status change Apr 26 11:36:04 rivo volumio[3585]: info: MCU Signalled Playback Inactive Apr 26 11:36:04 rivo volumio[3585]: info: camilladsp spawned new process with pid 6520, instance 1, run: true Apr 26 11:36:04 rivo volumio[3585]: info: MCU Signalled Playback Active Apr 26 11:36:04 rivo volumio[3585]: info: explodeUri took 680 milliseconds Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPushQueue Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::saveQueue Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::updateTrackBlock Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::getTrackBlock Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioPlay Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::play index 0 Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::addQueueItems Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::addQueueItems Apr 26 11:36:04 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75931690 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/75931690 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75926439 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/75926439 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75936074 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/75936074 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75931754 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/75931754 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75934806 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/75934806 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75930565 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/75930565 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/291113318 Apr 26 11:36:04 rivo volumio[3585]: info: Exploding uri qobuz://song/291113318 in service qobuz Apr 26 11:36:04 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::stop Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::play index undefined Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:04 rivo volumio[3585]: info: CoreStateMachine::startPlaybackTimer Apr 26 11:36:04 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 26 11:36:04 rivo volumio[3585]: info: [1745660164590] ControllerQobuz::clearAddPlayTrack Apr 26 11:36:04 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 11:36:04 rivo volumio[3585]: info: sendMpdCommand stop took 45 milliseconds Apr 26 11:36:04 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 11:36:04 rivo volumio[3585]: info: Apr 26 11:36:04 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:04 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:04 rivo volumio[3585]: info: sendMpdCommand clear took 4 milliseconds Apr 26 11:36:04 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/75927249" Apr 26 11:36:04 rivo volumio[3585]: info: Apr 26 11:36:04 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:04 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:04 rivo volumio[3585]: STREAMING PROXY: Handling url /?data=qobuz://song/75927249 Apr 26 11:36:04 rivo volumio[3585]: info: Apr 26 11:36:04 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:04 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:04 rivo volumio[3585]: info: Apr 26 11:36:04 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:04 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:04 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:04 rivo volumio[3585]: info: ------------------------------ 8ms Apr 26 11:36:04 rivo volumio[3585]: info: Executing endpoint getStreamUrlqobuz Apr 26 11:36:04 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 464 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 490 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 497 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 525 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 561 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 561 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: explodeUri took 583 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: CoreCommandRouter::volumioPushQueue Apr 26 11:36:05 rivo volumio[3585]: info: CorePlayQueue::saveQueue Apr 26 11:36:05 rivo volumio[3585]: info: CoreStateMachine::updateTrackBlock Apr 26 11:36:05 rivo volumio[3585]: info: CorePlayQueue::getTrackBlock Apr 26 11:36:05 rivo volumio[3585]: info: getStreamUrl took 376 milliseconds Apr 26 11:36:05 rivo volumio[3585]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2954991&eid=75927249&fmt=6&profile=raw&app_id=539451548&cid=2724506&etsp=1745663765&hmac=Z6gSx1-TNeFu4f2pmnLlyUq1Dl8 Apr 26 11:36:05 rivo volumio[3585]: STREAMING PROXY: Response: 200, length: 31018343 Apr 26 11:36:05 rivo volumio[3585]: STREAMING PROXY: Client dropped request, destroying Apr 26 11:36:05 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/75927249" Apr 26 11:36:05 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:05 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:05 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 1073ms Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 1071ms Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 1070ms Apr 26 11:36:05 rivo volumio[3585]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/75927249" took 2 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 11:36:05 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand play Apr 26 11:36:05 rivo volumio[3585]: info: Apr 26 11:36:05 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:05 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:05 rivo volumio[3585]: info: Apr 26 11:36:05 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:05 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:05 rivo volumio[3585]: STREAMING PROXY: Handling url /?data=qobuz://song/75927249 Apr 26 11:36:05 rivo volumio[3585]: info: sendMpdCommand play took 4 milliseconds Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 3ms Apr 26 11:36:05 rivo volumio[3585]: info: Apr 26 11:36:05 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:05 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:05 rivo volumio[3585]: info: Apr 26 11:36:05 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:05 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 7ms Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 4ms Apr 26 11:36:05 rivo volumio[3585]: info: ------------------------------ 3ms Apr 26 11:36:05 rivo volumio[3585]: info: Executing endpoint getStreamUrlqobuz Apr 26 11:36:05 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 26 11:36:06 rivo volumio[3585]: info: getStreamUrl took 335 milliseconds Apr 26 11:36:06 rivo volumio[3585]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2954991&eid=75927249&fmt=6&profile=raw&app_id=539451548&cid=2724506&etsp=1745663765&hmac=Z6gSx1-TNeFu4f2pmnLlyUq1Dl8 Apr 26 11:36:06 rivo volumio[3585]: STREAMING PROXY: Response: 200, length: 31018343 Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 26 11:36:06 rivo volumio[3585]: error: FusionDsp - File size not found in array! Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - Nulleq applied Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 26 11:36:06 rivo volumio[3585]: error: FusionDsp - File size not found in array! Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - Nulleq applied Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 47 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 45 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 44 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 35 milliseconds Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: Apr 26 11:36:06 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 20 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 14 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 12 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand status took 10 milliseconds Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"770 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus stop Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"875 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"875 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"875 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 138ms Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 190ms Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 191ms Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 192ms Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 138 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 135 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 167 milliseconds Apr 26 11:36:06 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 193 milliseconds Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:06 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:06 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:06 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"75927249","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75927249","trackType":"qobuz"} Apr 26 11:36:06 rivo volumio[3585]: verbose: CURRENT POSITION 0 Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:06 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:06 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:06 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 498ms Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 497ms Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 499ms Apr 26 11:36:06 rivo volumio[3585]: info: ------------------------------ 511ms Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 26 11:36:06 rivo volumio[3585]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 26 11:36:07 rivo volumio[3585]: STREAMING PROXY: Client dropped request, destroying Apr 26 11:36:08 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:36:08 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:36:08 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:36:08 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:36:08 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:36:08 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:36:11 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::ClearQueue Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::stop Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::stPlaybackTimer Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::updateTrackBlock Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrackBlock Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::serviceStop Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 0 Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::serviceStop Apr 26 11:36:11 rivo volumio[3585]: info: [1745660171041] ControllerQobuz::stop Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::stop Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::clearPlayQueue Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::saveQueue Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushQueue Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::addQueueItems Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::addQueueItems Apr 26 11:36:11 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75927249 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75927249 Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75931690 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75931690 Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushQueue Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::saveQueue Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::updateTrackBlock Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrackBlock Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPlay Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::play index 1 Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::addQueueItems Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::addQueueItems Apr 26 11:36:11 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75926439 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75926439 Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75936074 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75936074 Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75931754 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75931754 Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75934806 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75934806 Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/75930565 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/75930565 Apr 26 11:36:11 rivo volumio[3585]: info: Adding Item to queue: qobuz://song/291113318 Apr 26 11:36:11 rivo volumio[3585]: info: Using cached record of: qobuz://song/291113318 Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::stop Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushQueue Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::saveQueue Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::play index undefined Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::updateTrackBlock Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrackBlock Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::startPlaybackTimer Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: [1745660171077] ControllerQobuz::clearAddPlayTrack Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand stop took 73 milliseconds Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand stop took 37 milliseconds Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:11 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand status took 17 milliseconds Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand clear took 16 milliseconds Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand status took 14 milliseconds Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand status took 12 milliseconds Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand status took 10 milliseconds Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/75931690" Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:11 rivo volumio[3585]: STREAMING PROXY: Handling url /?data=qobuz://song/75931690 Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 26 11:36:11 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:11 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:11 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:11 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:11 rivo volumio[3585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 26 11:36:11 rivo volumio[3585]: info: ------------------------------ 77ms Apr 26 11:36:11 rivo volumio[3585]: info: ------------------------------ 75ms Apr 26 11:36:11 rivo volumio[3585]: info: ------------------------------ 74ms Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:11 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:11 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:11 rivo volumio[3585]: info: Apr 26 11:36:11 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:11 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:11 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:11 rivo volumio[3585]: info: ------------------------------ 95ms Apr 26 11:36:11 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 91 milliseconds Apr 26 11:36:11 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:11 rivo volumio[3585]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 26 11:36:11 rivo volumio[3585]: info: ------------------------------ 116ms Apr 26 11:36:11 rivo volumio[3585]: info: Executing endpoint getStreamUrlqobuz Apr 26 11:36:11 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 26 11:36:11 rivo volumio[3585]: info: MCU Signalled Playback Inactive Apr 26 11:36:11 rivo volumio[3585]: info: camilladsp spawned new process with pid 6553, instance 1, run: true Apr 26 11:36:11 rivo volumio[3585]: info: getStreamUrl took 396 milliseconds Apr 26 11:36:11 rivo volumio[3585]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2954991&eid=75931690&fmt=6&profile=raw&app_id=539451548&cid=2724506&etsp=1745663771&hmac=WxNqeQyQQw8JTLCpw8rZgmlViKw Apr 26 11:36:12 rivo volumio[3585]: STREAMING PROXY: Response: 200, length: 30883139 Apr 26 11:36:12 rivo volumio[3585]: STREAMING PROXY: Client dropped request, destroying Apr 26 11:36:12 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:12 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:12 rivo volumio[3585]: error: updateQueue error: null Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/75931690" Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 814ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 810ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 805ms Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:12 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/75931690" took 3 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand play Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:12 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:12 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces system playlist update Apr 26 11:36:12 rivo volumio[3585]: STREAMING PROXY: Handling url /?data=qobuz://song/75931690 Apr 26 11:36:12 rivo volumio[3585]: info: Ignoring MPD Status Update Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 8ms Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand play took 7 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 6ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 5ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 3ms Apr 26 11:36:12 rivo volumio[3585]: info: Executing endpoint getStreamUrlqobuz Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 26 11:36:12 rivo volumio[3585]: info: getStreamUrl took 372 milliseconds Apr 26 11:36:12 rivo volumio[3585]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2954991&eid=75931690&fmt=6&profile=raw&app_id=539451548&cid=2724506&etsp=1745663772&hmac=TEuZNzL6qCZzWp3sBKhC-1XQrCw Apr 26 11:36:12 rivo volumio[3585]: STREAMING PROXY: Response: 200, length: 30883139 Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: FusionDsp - ---- read samplerate, raw: Apr 26 11:36:12 rivo volumio[3585]: error: FusionDsp - invalid sample rate Apr 26 11:36:12 rivo volumio[3585]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 26 11:36:12 rivo volumio[3585]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 26 11:36:12 rivo volumio[3585]: error: FusionDsp - File size not found in array! Apr 26 11:36:12 rivo volumio[3585]: info: FusionDsp - Nulleq applied Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 34 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 33 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 33 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 32 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: Apr 26 11:36:12 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 11 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 6 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 5 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand status took 3 milliseconds Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus stop Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 98ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 129ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 130ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 129ms Apr 26 11:36:12 rivo volumio[3585]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 80 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 26 11:36:12 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:12 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:12 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:12 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:12 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:12 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:12 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:12 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 325ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 311ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 312ms Apr 26 11:36:12 rivo volumio[3585]: info: ------------------------------ 313ms Apr 26 11:36:13 rivo volumio[3585]: info: Signalling Playback active due to playback status change Apr 26 11:36:13 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:36:13 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:36:13 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:36:13 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:36:13 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:36:13 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:36:13 rivo volumio[3585]: info: MCU Signalled Playback Active Apr 26 11:36:13 rivo ntpd[3569]: 90.68.206.60 local addr 192.168.1.140 -> Apr 26 11:36:15 rivo volumio[3585]: STREAMING PROXY: Client dropped request, destroying Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioSeek Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::seek Apr 26 11:36:21 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:21 rivo volumio[3585]: info: TRACKBLOCK {"album":"20th Anniversary","albumUri":"qobuz://album/cjs4dl0tkorqc","albumart":"https://static.qobuz.com/images/covers/qc/or/cjs4dl0tkorqc_600.jpg","artist":"El Gran Combo De Puerto Rico","artistUri":"qobuz://artist/22882","audioQuality":"","duration":264,"explicit":false,"name":"La Muerte","service":"qobuz","title":"La Muerte","tracknumber":1,"type":"track","uri":"qobuz://song/75931690","volumeNumber":1,"tagImage":""} Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::startPlaybackTimer Apr 26 11:36:21 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:21 rivo volumio[3585]: info: [1745660181608] ControllerQobuz::seek Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::seek Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: error: null Apr 26 11:36:21 rivo volumio[3585]: info: Apr 26 11:36:21 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:21 rivo volumio[3585]: info: Apr 26 11:36:21 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:21 rivo volumio[3585]: info: Apr 26 11:36:21 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:21 rivo volumio[3585]: info: Apr 26 11:36:21 rivo volumio[3585]: ---------------------------- MPD announces state update: player Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::getState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand status Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand status took 31 milliseconds Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand status took 29 milliseconds Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand status took 25 milliseconds Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand status took 23 milliseconds Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseState Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 26 11:36:21 rivo volumio[3585]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:21 rivo volumio[3585]: verbose: ControllerMpd::parseTrackInfo Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:21 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:21 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:21 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:21 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:21 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:21 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:21 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:21 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:21 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:21 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:21 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:21 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: ControllerMpd::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::servicePushState Apr 26 11:36:21 rivo volumio[3585]: info: CorePlayQueue::getTrack 1 Apr 26 11:36:21 rivo volumio[3585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84212,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"993 Kbps","isStreaming":false,"title":"75931690","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/75931690","trackType":"qobuz"} Apr 26 11:36:21 rivo volumio[3585]: verbose: CURRENT POSITION 1 Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState stateService play Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::syncState currentStatus play Apr 26 11:36:21 rivo volumio[3585]: info: Received an update from plugin. extracting info from payload Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: CoreStateMachine::pushState Apr 26 11:36:21 rivo volumio[3585]: info: CoreCommandRouter::volumioPushState Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:36:21 rivo volumio[3585]: info: MRS: Pushing multiroomSync output Apr 26 11:36:21 rivo volumio[3585]: info: ------------------------------ 154ms Apr 26 11:36:21 rivo volumio[3585]: info: ------------------------------ 153ms Apr 26 11:36:21 rivo volumio[3585]: info: ------------------------------ 152ms Apr 26 11:36:21 rivo volumio[3585]: info: ------------------------------ 151ms Apr 26 11:36:25 rivo volumio[3585]: info: Adding qobuz://song/75931690 to favourites with specific qobuz method Apr 26 11:36:26 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:36:26 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:36:26 rivo volumio[3585]: info: Discovery: Getting this device information Apr 26 11:36:26 rivo volumio[3585]: info: CoreCommandRouter::volumioGetState Apr 26 11:36:26 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:36:51 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:36:51 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:36:53 rivo volumio[3585]: info: searchQOBUZUri took 1206 milliseconds Apr 26 11:36:53 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:36:56 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:36:56 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:36:56 rivo volumio[3585]: info: Discovery: Getting this device information Apr 26 11:36:56 rivo volumio[3585]: info: CoreCommandRouter::volumioGetState Apr 26 11:36:56 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:36:57 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:36:57 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:36:59 rivo volumio[3585]: info: searchQOBUZUri took 1422 milliseconds Apr 26 11:36:59 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:36:59 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:36:59 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:00 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:37:00 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:01 rivo volumio[3585]: info: searchQOBUZUri took 1811 milliseconds Apr 26 11:37:01 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:01 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:37:01 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:02 rivo volumio[3585]: info: searchQOBUZUri took 1287 milliseconds Apr 26 11:37:02 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:03 rivo volumio[3585]: info: searchQOBUZUri took 1435 milliseconds Apr 26 11:37:03 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:07 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:37:07 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:08 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:37:08 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:09 rivo volumio[3585]: info: searchQOBUZUri took 1413 milliseconds Apr 26 11:37:09 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:09 rivo volumio[3585]: info: searchQOBUZUri took 1309 milliseconds Apr 26 11:37:09 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:16 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:37:16 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:18 rivo volumio[3585]: info: searchQOBUZUri took 1111 milliseconds Apr 26 11:37:18 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:26 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:37:26 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:37:26 rivo volumio[3585]: info: Discovery: Getting this device information Apr 26 11:37:26 rivo volumio[3585]: info: CoreCommandRouter::volumioGetState Apr 26 11:37:26 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:37:29 rivo volumio[3585]: Searching plugin music_service/qobuz Apr 26 11:37:29 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 26 11:37:30 rivo volumio[3585]: info: searchQOBUZUri took 846 milliseconds Apr 26 11:37:30 rivo volumio[3585]: info: All search sources collected, pushing search results Apr 26 11:37:35 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 26 11:37:36 rivo volumio[3585]: info: handleBrowseUri took 537 milliseconds Apr 26 11:37:36 rivo volumio[3585]: info: Preload queue cleared Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375207 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375208 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375209 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375210 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375211 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375212 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375213 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375214 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375215 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375216 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375217 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375218 Apr 26 11:37:36 rivo volumio[3585]: info: Preloading song: qobuz://song/249375219 Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375207 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375208 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375209 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375210 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375211 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375212 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375213 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375214 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375215 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375216 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: explodeUri took 503 milliseconds Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375217 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:36 rivo volumio[3585]: info: explodeUri took 428 milliseconds Apr 26 11:37:36 rivo volumio[3585]: info: Exploding uri qobuz://song/249375218 in service qobuz Apr 26 11:37:36 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 561 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: Exploding uri qobuz://song/249375219 in service qobuz Apr 26 11:37:37 rivo volumio[3585]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 539 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 552 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 625 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 630 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 729 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 605 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 611 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 669 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 594 milliseconds Apr 26 11:37:37 rivo volumio[3585]: info: explodeUri took 742 milliseconds Apr 26 11:37:38 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: Executing endpoint getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 26 11:37:38 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:38 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:38 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:38 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:39 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:39 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:39 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:39 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:40 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:40 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:40 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:40 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:41 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:41 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:41 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:41 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:42 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:42 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:42 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:42 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:42 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:42 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:43 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:43 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:43 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:43 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:43 rivo volumio[3585]: info: Executing endpoint metavolumio Apr 26 11:37:43 rivo volumio[3585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 11:37:44 rivo volumio[3585]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 11:37:44 rivo volumio[3585]: Error: self signed certificate Apr 26 11:37:44 rivo volumio[3585]: at TLSSocket.onConnectSecure (_tls_wrap.js:1515:34) Apr 26 11:37:44 rivo volumio[3585]: at TLSSocket.emit (events.js:400:28) Apr 26 11:37:44 rivo volumio[3585]: at TLSSocket._finishInit (_tls_wrap.js:937:8) Apr 26 11:37:44 rivo volumio[3585]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:709:12) { Apr 26 11:37:44 rivo volumio[3585]: code: 'DEPTH_ZERO_SELF_SIGNED_CERT' Apr 26 11:37:44 rivo volumio[3585]: } Apr 26 11:37:44 rivo volumio[3585]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 11:37:44 rivo sudo[6825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 11:36 Apr 26 11:37:44 rivo sudo[6825]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 08:01:39 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="71b19b3a3eb3d257ef93eee35eba5cf1"