-- Logs begin at Tue 2026-01-27 04:20:30 CET, end at Wed 2026-01-28 06:23:29 CET. -- Jan 28 06:22:00 rivo volumio[3411]: info: Exploding uri qobuz://song/57619335 in service qobuz Jan 28 06:22:00 rivo volumio[3411]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 28 06:22:00 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:00 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:00 rivo volumio[3411]: info: Exploding uri qobuz://song/57619336 in service qobuz Jan 28 06:22:00 rivo volumio[3411]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 383 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 423 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 297 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 493 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 367 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 336 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 246 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 297 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 223 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: explodeUri took 199 milliseconds Jan 28 06:22:00 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:00 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:00 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:00 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:00 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:00 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:01 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:01 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:02 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:02 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:02 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:02 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:03 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:03 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:03 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:03 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:03 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:03 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:07 rivo volumio[3411]: info: Preload queue cleared Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::ClearQueue Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::stop Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::stPlaybackTimer Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::updateTrackBlock Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrackBlock Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 5 Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 5 Jan 28 06:22:07 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:07 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::serviceStop Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 5 Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::serviceStop Jan 28 06:22:07 rivo volumio[3411]: info: [1769577727635] ControllerQobuz::stop Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 06:22:07 rivo volumio[3411]: info: ControllerMpd::stop Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::clearPlayQueue Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::saveQueue Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioPushQueue Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::addQueueItems Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::addQueueItems Jan 28 06:22:07 rivo volumio[3411]: info: Preload queue cleared Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619322 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619322 Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioPushQueue Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::saveQueue Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::updateTrackBlock Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrackBlock Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioPlay Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::play index 0 Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::addQueueItems Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::addQueueItems Jan 28 06:22:07 rivo volumio[3411]: info: Preload queue cleared Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619323 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619323 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619324 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619324 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619325 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619325 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619326 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619326 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619327 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619327 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619328 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619328 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619329 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619329 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619330 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619330 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619331 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619331 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619332 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619332 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619333 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619333 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619334 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619334 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619335 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619335 Jan 28 06:22:07 rivo volumio[3411]: info: Adding Item to queue: qobuz://song/57619336 Jan 28 06:22:07 rivo volumio[3411]: info: Using cached record of: qobuz://song/57619336 Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::stop Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioPushQueue Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::saveQueue Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::play index undefined Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::updateTrackBlock Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrackBlock Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::startPlaybackTimer Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:07 rivo volumio[3411]: info: [1769577727693] ControllerQobuz::clearAddPlayTrack Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 06:22:07 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:07 rivo volumio[3411]: info: Jan 28 06:22:07 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:07 rivo volumio[3411]: info: sendMpdCommand stop took 82 milliseconds Jan 28 06:22:07 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:07 rivo volumio[3411]: info: sendMpdCommand stop took 24 milliseconds Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 06:22:07 rivo volumio[3411]: info: Jan 28 06:22:07 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:07 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:07 rivo volumio[3411]: info: Jan 28 06:22:07 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:07 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:07 rivo volumio[3411]: info: sendMpdCommand status took 6 milliseconds Jan 28 06:22:07 rivo volumio[3411]: info: sendMpdCommand clear took 5 milliseconds Jan 28 06:22:07 rivo volumio[3411]: info: sendMpdCommand status took 4 milliseconds Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/57619322" Jan 28 06:22:07 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:07 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:07 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:07 rivo volumio[3411]: STREAMING PROXY: Handling url /?data=qobuz://song/57619322 Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:07 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:07 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:07 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:07 rivo volumio[3411]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 28 06:22:07 rivo volumio[3411]: info: ------------------------------ 30ms Jan 28 06:22:07 rivo volumio[3411]: info: Jan 28 06:22:07 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:07 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:07 rivo volumio[3411]: error: updateQueue error: null Jan 28 06:22:07 rivo volumio[3411]: info: ------------------------------ 34ms Jan 28 06:22:07 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:07 rivo volumio[3411]: info: Executing endpoint getStreamUrlqobuz Jan 28 06:22:07 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 06:22:07 rivo volumio[3411]: info: MCU Signalled Playback Inactive Jan 28 06:22:07 rivo volumio[3411]: info: getStreamUrl took 192 milliseconds Jan 28 06:22:07 rivo volumio[3411]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193522&eid=57619322&fmt=7&profile=raw&app_id=539451548&cid=3519369&etsp=1769581327&hmac=bztu3CsB0_y1WYWYKmNLcAKNlaM Jan 28 06:22:07 rivo volumio[3411]: STREAMING PROXY: Requesting upstream with range: none Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Upstream status code: 200 Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Client closed connection Jan 28 06:22:08 rivo volumio[3411]: error: updateQueue error: null Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Upstream closed. Received 15641 bytes Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 422 milliseconds Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Incomplete transfer. Expected 38435091, got 15641 Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Retrying with range: bytes=15641- (attempt 1) Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/57619322" Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 398ms Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:08 rivo volumio[3411]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 434ms Jan 28 06:22:08 rivo volumio[3411]: info: Jan 28 06:22:08 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:08 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/57619322" took 4 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand play Jan 28 06:22:08 rivo volumio[3411]: info: Jan 28 06:22:08 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:08 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Handling url /?data=qobuz://song/57619322 Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 9ms Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand play took 5 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 4ms Jan 28 06:22:08 rivo volumio[3411]: info: Executing endpoint getStreamUrlqobuz Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 06:22:08 rivo volumio[3411]: info: getStreamUrl took 222 milliseconds Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193522&eid=57619322&fmt=7&profile=raw&app_id=539451548&cid=3519369&etsp=1769581328&hmac=Xry0Yba0IF4aPYzqkp3AnJMZGkY Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Requesting upstream with range: none Jan 28 06:22:08 rivo volumio[3411]: STREAMING PROXY: Upstream status code: 200 Jan 28 06:22:08 rivo volumio[3411]: info: Jan 28 06:22:08 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:08 rivo volumio[3411]: info: Jan 28 06:22:08 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:08 rivo volumio[3411]: info: Jan 28 06:22:08 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand status took 21 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand status took 23 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand status took 4 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:08 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:08 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619322","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619322","trackType":"qobuz"} Jan 28 06:22:08 rivo volumio[3411]: verbose: CURRENT POSITION 0 Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus stop Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 33ms Jan 28 06:22:08 rivo volumio[3411]: info: Jan 28 06:22:08 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand status took 7 milliseconds Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:08 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:08 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619322","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619322","trackType":"qobuz"} Jan 28 06:22:08 rivo volumio[3411]: verbose: CURRENT POSITION 0 Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus play Jan 28 06:22:08 rivo volumio[3411]: info: Received an update from plugin. extracting info from payload Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:08 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:08 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619322","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619322","trackType":"qobuz"} Jan 28 06:22:08 rivo volumio[3411]: verbose: CURRENT POSITION 0 Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus play Jan 28 06:22:08 rivo volumio[3411]: info: Received an update from plugin. extracting info from payload Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 146ms Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 131ms Jan 28 06:22:08 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 112 milliseconds Jan 28 06:22:08 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:08 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:08 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:08 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619322","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619322","trackType":"qobuz"} Jan 28 06:22:08 rivo volumio[3411]: verbose: CURRENT POSITION 0 Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus play Jan 28 06:22:08 rivo volumio[3411]: info: Received an update from plugin. extracting info from payload Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:08 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:08 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:08 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:08 rivo volumio[3411]: info: ------------------------------ 175ms Jan 28 06:22:08 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:08 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:08 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:08 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:08 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:08 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:08 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:08 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:08 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:08 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:08 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:08 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:08 rivo volumio[3411]: info: MCU Signalled Playback Active Jan 28 06:22:09 rivo volumio[3411]: STREAMING PROXY: Resuming with range: bytes=15641- Jan 28 06:22:09 rivo volumio[3411]: STREAMING PROXY: Resume upstream status code: 206 Jan 28 06:22:09 rivo volumio[3411]: STREAMING PROXY: Upstream closed. Received 38435091 bytes Jan 28 06:22:09 rivo volumio[3411]: STREAMING PROXY: Client closed connection Jan 28 06:22:10 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:10 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:10 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:10 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:10 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:10 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:11 rivo volumio[3411]: STREAMING PROXY: Resume ended. Received 38419450 bytes Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::volumioNext Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::next Jan 28 06:22:27 rivo volumio[3411]: info: [1769577747390] ControllerQobuz::next Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::next Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::stop Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::stPlaybackTimer Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::updateTrackBlock Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrackBlock Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:27 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:27 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::serviceStop Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 0 Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::serviceStop Jan 28 06:22:27 rivo volumio[3411]: info: [1769577747406] ControllerQobuz::stop Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 06:22:27 rivo volumio[3411]: info: ControllerMpd::stop Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 06:22:27 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:27 rivo volumio[3411]: info: Jan 28 06:22:27 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand stop took 67 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::play index undefined Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::startPlaybackTimer Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:27 rivo volumio[3411]: info: [1769577747478] ControllerQobuz::clearAddPlayTrack Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::updateTrackBlock Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrackBlock Jan 28 06:22:27 rivo volumio[3411]: info: Jan 28 06:22:27 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:27 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand status took 17 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand stop took 14 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand status took 7 milliseconds Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:27 rivo volumio[3411]: info: Jan 28 06:22:27 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:27 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand clear took 5 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/57619323" Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:27 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:27 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:27 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:27 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:27 rivo volumio[3411]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 28 06:22:27 rivo volumio[3411]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Handling url /?data=qobuz://song/57619323 Jan 28 06:22:27 rivo volumio[3411]: info: ------------------------------ 56ms Jan 28 06:22:27 rivo volumio[3411]: info: ------------------------------ 49ms Jan 28 06:22:27 rivo volumio[3411]: info: Jan 28 06:22:27 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:27 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:27 rivo volumio[3411]: error: updateQueue error: null Jan 28 06:22:27 rivo volumio[3411]: info: ------------------------------ 36ms Jan 28 06:22:27 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:27 rivo volumio[3411]: info: MCU Signalled Playback Inactive Jan 28 06:22:27 rivo volumio[3411]: info: Executing endpoint getStreamUrlqobuz Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 06:22:27 rivo volumio[3411]: info: getStreamUrl took 173 milliseconds Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193522&eid=57619323&fmt=7&profile=raw&app_id=539451548&cid=3519369&etsp=1769581347&hmac=P2MpXu8QCubc8VrakbEDNmTRR4E Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Requesting upstream with range: none Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Upstream status code: 200 Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Client closed connection Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/57619323" Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Upstream closed. Received 15641 bytes Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Incomplete transfer. Expected 43614724, got 15641 Jan 28 06:22:27 rivo volumio[3411]: error: updateQueue error: null Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Retrying with range: bytes=15641- (attempt 1) Jan 28 06:22:27 rivo volumio[3411]: info: ------------------------------ 417ms Jan 28 06:22:27 rivo volumio[3411]: info: Jan 28 06:22:27 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:27 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/57619323" took 3 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 06:22:27 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand play Jan 28 06:22:27 rivo volumio[3411]: info: Jan 28 06:22:27 rivo volumio[3411]: ---------------------------- MPD announces system playlist update Jan 28 06:22:27 rivo volumio[3411]: info: Ignoring MPD Status Update Jan 28 06:22:27 rivo volumio[3411]: STREAMING PROXY: Handling url /?data=qobuz://song/57619323 Jan 28 06:22:27 rivo volumio[3411]: info: ------------------------------ 9ms Jan 28 06:22:27 rivo volumio[3411]: info: sendMpdCommand play took 8 milliseconds Jan 28 06:22:27 rivo volumio[3411]: info: ------------------------------ 8ms Jan 28 06:22:27 rivo volumio[3411]: info: Executing endpoint getStreamUrlqobuz Jan 28 06:22:27 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 06:22:28 rivo volumio[3411]: info: getStreamUrl took 198 milliseconds Jan 28 06:22:28 rivo volumio[3411]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1193522&eid=57619323&fmt=7&profile=raw&app_id=539451548&cid=3519369&etsp=1769581348&hmac=8k2AUXL4kq6QXcTCxp6sXwOCsiQ Jan 28 06:22:28 rivo volumio[3411]: STREAMING PROXY: Requesting upstream with range: none Jan 28 06:22:28 rivo volumio[3411]: STREAMING PROXY: Upstream status code: 200 Jan 28 06:22:28 rivo volumio[3411]: info: Jan 28 06:22:28 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:28 rivo volumio[3411]: info: Jan 28 06:22:28 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:28 rivo volumio[3411]: info: Jan 28 06:22:28 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand status took 20 milliseconds Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand status took 19 milliseconds Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:28 rivo volumio[3411]: info: Jan 28 06:22:28 rivo volumio[3411]: ---------------------------- MPD announces state update: player Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::getState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand status Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand status took 25 milliseconds Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand status took 22 milliseconds Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseState Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:28 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:28 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619323","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619323","trackType":"qobuz"} Jan 28 06:22:28 rivo volumio[3411]: verbose: CURRENT POSITION 1 Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus stop Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:28 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:28 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619323","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619323","trackType":"qobuz"} Jan 28 06:22:28 rivo volumio[3411]: verbose: CURRENT POSITION 1 Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus play Jan 28 06:22:28 rivo volumio[3411]: info: Received an update from plugin. extracting info from payload Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:28 rivo volumio[3411]: info: ------------------------------ 128ms Jan 28 06:22:28 rivo volumio[3411]: info: ------------------------------ 193ms Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 132 milliseconds Jan 28 06:22:28 rivo volumio[3411]: info: sendMpdCommand playlistinfo took 131 milliseconds Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:28 rivo volumio[3411]: verbose: ControllerMpd::parseTrackInfo Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:28 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:28 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619323","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619323","trackType":"qobuz"} Jan 28 06:22:28 rivo volumio[3411]: verbose: CURRENT POSITION 1 Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus play Jan 28 06:22:28 rivo volumio[3411]: info: Received an update from plugin. extracting info from payload Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:28 rivo volumio[3411]: info: ControllerMpd::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::servicePushState Jan 28 06:22:28 rivo volumio[3411]: info: CorePlayQueue::getTrack 1 Jan 28 06:22:28 rivo volumio[3411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57619323","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/57619323","trackType":"qobuz"} Jan 28 06:22:28 rivo volumio[3411]: verbose: CURRENT POSITION 1 Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState stateService play Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::syncState currentStatus play Jan 28 06:22:28 rivo volumio[3411]: info: Received an update from plugin. extracting info from payload Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:28 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:28 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:28 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:28 rivo volumio[3411]: info: ------------------------------ 333ms Jan 28 06:22:28 rivo volumio[3411]: info: ------------------------------ 311ms Jan 28 06:22:28 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:28 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:28 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:28 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:28 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:28 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:28 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:28 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:28 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:28 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:28 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:28 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:28 rivo volumio[3411]: info: MCU Signalled Playback Active Jan 28 06:22:29 rivo volumio[3411]: STREAMING PROXY: Resuming with range: bytes=15641- Jan 28 06:22:29 rivo volumio[3411]: STREAMING PROXY: Resume upstream status code: 206 Jan 28 06:22:30 rivo volumio[3411]: STREAMING PROXY: Upstream closed. Received 43614724 bytes Jan 28 06:22:30 rivo volumio[3411]: STREAMING PROXY: Client closed connection Jan 28 06:22:30 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:30 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:30 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:30 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:31 rivo volumio[3411]: STREAMING PROXY: Resume ended. Received 43599083 bytes Jan 28 06:22:36 rivo volumio[3411]: info: VolumeController::SetAlsaVolume50 Jan 28 06:22:36 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:36 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:36 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:36 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:36 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:36 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:36 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:36 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:36 rivo volumio[3411]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 28 06:22:36 rivo volumio[3411]: xcb_connection_has_error() returned true Jan 28 06:22:39 rivo volumio[3411]: info: VolumeController::SetAlsaVolume65 Jan 28 06:22:39 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:39 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:39 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:39 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:39 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:39 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:39 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:39 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:39 rivo volumio[3411]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 28 06:22:39 rivo volumio[3411]: xcb_connection_has_error() returned true Jan 28 06:22:41 rivo volumio[3411]: info: VolumeController::SetAlsaVolume- Jan 28 06:22:41 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:41 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:41 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:41 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:41 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:41 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:41 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:41 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:41 rivo volumio[3411]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 28 06:22:41 rivo volumio[3411]: xcb_connection_has_error() returned true Jan 28 06:22:41 rivo volumio[3411]: info: VolumeController::SetAlsaVolume- Jan 28 06:22:41 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:41 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:41 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:41 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:41 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:41 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:41 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:41 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:41 rivo volumio[3411]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 28 06:22:41 rivo volumio[3411]: xcb_connection_has_error() returned true Jan 28 06:22:42 rivo volumio[3411]: info: VolumeController::SetAlsaVolume- Jan 28 06:22:42 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:42 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:42 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:42 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:42 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:42 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:42 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:42 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:42 rivo volumio[3411]: info: VolumeController::SetAlsaVolume- Jan 28 06:22:42 rivo volumio[3411]: info: CoreStateMachine::pushState Jan 28 06:22:42 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 06:22:42 rivo volumio[3411]: info: CoreCommandRouter::volumioPushState Jan 28 06:22:42 rivo volumio[3411]: info: CoreCommandRouter::volumioGetState Jan 28 06:22:42 rivo volumio[3411]: info: MRS: Pushing multiroomSync output update for this device Jan 28 06:22:42 rivo volumio[3411]: info: MRS: Pushing multiroomSync output Jan 28 06:22:42 rivo volumio[3411]: info: Signalling Playback active due to playback status change Jan 28 06:22:42 rivo volumio[3411]: info: Updating RAAT Signal Path Jan 28 06:22:42 rivo volumio[3411]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 28 06:22:42 rivo volumio[3411]: xcb_connection_has_error() returned true Jan 28 06:22:50 rivo wpa_supplicant[3173]: wlan0: WPA: Group rekeying completed with 5c:fa:25:85:b6:8f [GTK=CCMP] Jan 28 06:22:55 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:55 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:22:55 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:22:55 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:09 rivo volumio[3411]: Searching plugin music_service/qobuz Jan 28 06:23:09 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 28 06:23:10 rivo volumio[3411]: info: searchQOBUZUri took 737 milliseconds Jan 28 06:23:10 rivo volumio[3411]: info: All search sources collected, pushing search results Jan 28 06:23:11 rivo volumio[3411]: Searching plugin music_service/qobuz Jan 28 06:23:11 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 28 06:23:11 rivo volumio[3411]: info: searchQOBUZUri took 792 milliseconds Jan 28 06:23:11 rivo volumio[3411]: info: All search sources collected, pushing search results Jan 28 06:23:12 rivo volumio[3411]: Searching plugin music_service/qobuz Jan 28 06:23:12 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 28 06:23:12 rivo volumio[3411]: info: searchQOBUZUri took 680 milliseconds Jan 28 06:23:12 rivo volumio[3411]: info: All search sources collected, pushing search results Jan 28 06:23:20 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 28 06:23:20 rivo volumio[3411]: info: handleBrowseUri took 476 milliseconds Jan 28 06:23:20 rivo volumio[3411]: info: Preload queue cleared Jan 28 06:23:20 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:20 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:21 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:21 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:21 rivo volumio[3411]: info: Executing endpoint getSimilarArtists Jan 28 06:23:21 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 06:23:21 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:21 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:21 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:21 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:22 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:22 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:22 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:22 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:22 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:22 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:24 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:24 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:24 rivo volumio[3411]: info: Executing endpoint metavolumio Jan 28 06:23:24 rivo volumio[3411]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 06:23:28 rivo volumio[3411]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kari%20Bremnes/2cc14806-470e-443d-b77f-7bda8e27dfe8.jpg' Jan 28 06:23:28 rivo volumio[3411]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 06:23:28 rivo volumio[3411]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 06:23:28 rivo volumio[3411]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 28 06:23:28 rivo volumio[3411]: errno: -111, Jan 28 06:23:28 rivo volumio[3411]: code: 'ECONNREFUSED', Jan 28 06:23:28 rivo volumio[3411]: syscall: 'connect', Jan 28 06:23:28 rivo volumio[3411]: address: '158.69.210.98', Jan 28 06:23:28 rivo volumio[3411]: port: 443 Jan 28 06:23:28 rivo volumio[3411]: } Jan 28 06:23:28 rivo volumio[3411]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 06:23:29 rivo sudo[8655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 06:22 Jan 28 06:23:29 rivo sudo[8655]: 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="58e9bc1d7073d86e583c7b0145c7ac1a2c50cfc4" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 16 Jan 2026 06:34:04 PM CET" VOLUMIO_VERSION="3.896" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e7349afa9f70d6443365ce76664cdf21"