-- Logs begin at Fri 2026-01-30 04:17:05 CET, end at Sun 2026-02-08 13:47:44 CET. --
Feb 08 13:46:02 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Feb 08 13:46:03 rivo volumio[3552]: info: handleBrowseUri took 112 milliseconds
Feb 08 13:46:03 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:03 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:08 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Feb 08 13:46:08 rivo volumio[3552]: info: handleBrowseUri took 459 milliseconds
Feb 08 13:46:08 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:15 rivo volumio[3552]: info: Listing playlists
Feb 08 13:46:17 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , explodeUri
Feb 08 13:46:17 rivo volumio[3552]: https://prod.vlmapi.io/v2/qobuz/explodeUri
Feb 08 13:46:18 rivo volumio[3552]: info: explodeUri took 243 milliseconds
Feb 08 13:46:18 rivo volumio[3552]: info: Saving local item Test phase
Feb 08 13:46:18 rivo volumio[3552]: info: Pushing Favourites {"uri":"qobuz://album/liiwmytrukvyc","favourite":false}
Feb 08 13:46:18 rivo volumio[3552]: info: Listing playlists
Feb 08 13:46:26 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Feb 08 13:46:26 rivo volumio[3552]: info: handleBrowseUri took 179 milliseconds
Feb 08 13:46:26 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 08 13:46:33 rivo volumio[3552]: info: CURURI: playlists
Feb 08 13:46:33 rivo volumio[3552]: info: Listing playlists
Feb 08 13:46:33 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:34 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 08 13:46:34 rivo volumio[3552]: info: CURURI: playlists/Test phase
Feb 08 13:46:34 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:34 rivo volumio[3552]: info: Preloading song: qobuz://album/liiwmytrukvyc
Feb 08 13:46:34 rivo volumio[3552]: info: Exploding uri qobuz://album/liiwmytrukvyc in service qobuz
Feb 08 13:46:34 rivo volumio[3552]: https://prod.vlmapi.io/v2/qobuz/explodeUri
Feb 08 13:46:34 rivo volumio[3552]: info: explodeUri took 167 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::ClearQueue
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::stop
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::stPlaybackTimer
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::updateTrackBlock
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrackBlock
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:50 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::serviceStop
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::serviceStop
Feb 08 13:46:50 rivo volumio[3552]: info: [1770554810210] ControllerQobuz::stop
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 08 13:46:50 rivo volumio[3552]: info: ControllerMpd::stop
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand stop
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::clearPlayQueue
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::saveQueue
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioPushQueue
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::addQueueItems
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::addQueueItems
Feb 08 13:46:50 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:50 rivo volumio[3552]: info: Adding Item to queue: qobuz://album/liiwmytrukvyc
Feb 08 13:46:50 rivo volumio[3552]: info: Using cached record of: qobuz://album/liiwmytrukvyc
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioPushQueue
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::saveQueue
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::updateTrackBlock
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrackBlock
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioPlay
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::play index 0
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::stop
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::play index undefined
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::startPlaybackTimer
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: [1770554810268] ControllerQobuz::clearAddPlayTrack
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand stop
Feb 08 13:46:50 rivo volumio[3552]: info:
Feb 08 13:46:50 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand stop took 97 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand stop took 42 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand clear
Feb 08 13:46:50 rivo volumio[3552]: info:
Feb 08 13:46:50 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:50 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:50 rivo volumio[3552]: info:
Feb 08 13:46:50 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:50 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:50 rivo volumio[3552]: info:
Feb 08 13:46:50 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:50 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand status took 16 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand clear took 16 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/138670640"
Feb 08 13:46:50 rivo volumio[3552]: info:
Feb 08 13:46:50 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:50 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:50 rivo volumio[3552]: STREAMING PROXY: Handling url /?data=qobuz://song/138670640
Feb 08 13:46:50 rivo volumio[3552]: error: updateQueue error: null
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand status took 20 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand status took 24 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: info: ------------------------------ 23ms
Feb 08 13:46:50 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 14 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:50 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:50 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:50 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd
Feb 08 13:46:50 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:50 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:50 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:50 rivo volumio[3552]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd
Feb 08 13:46:50 rivo volumio[3552]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Feb 08 13:46:50 rivo volumio[3552]: info: ------------------------------ 106ms
Feb 08 13:46:50 rivo volumio[3552]: info: ------------------------------ 104ms
Feb 08 13:46:50 rivo volumio[3552]: info: ------------------------------ 115ms
Feb 08 13:46:50 rivo volumio[3552]: info:
Feb 08 13:46:50 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:50 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:50 rivo volumio[3552]: info: MCU Signalled Playback Inactive
Feb 08 13:46:50 rivo volumio[3552]: info: Executing endpoint getStreamUrlqobuz
Feb 08 13:46:50 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Feb 08 13:46:50 rivo volumio[3552]: info: getStreamUrl took 212 milliseconds
Feb 08 13:46:50 rivo volumio[3552]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2302212&eid=138670640&fmt=27&profile=raw&app_id=539451548&cid=2307704&etsp=1770558410&hmac=0FreKFzrjuefOM642L4gigCI11Q
Feb 08 13:46:50 rivo volumio[3552]: STREAMING PROXY: Requesting upstream with range: none
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Upstream status code: 200
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Client closed connection
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138670640"
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Upstream closed. Received 442 bytes
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Incomplete transfer. Expected 119408864, got 442
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Retrying with range: bytes=442- (attempt 1)
Feb 08 13:46:51 rivo volumio[3552]: error: updateQueue error: null
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:51 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 1006ms
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 914ms
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138670640" took 5 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand play
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:51 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Handling url /?data=qobuz://song/138670640
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:51 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 18ms
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand play took 15 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 12ms
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 8ms
Feb 08 13:46:51 rivo volumio[3552]: info: Executing endpoint getStreamUrlqobuz
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Feb 08 13:46:51 rivo volumio[3552]: info: getStreamUrl took 250 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2302212&eid=138670640&fmt=27&profile=raw&app_id=539451548&cid=2307704&etsp=1770558411&hmac=0Zrdg12A07sbr9Xi0kTMyNetyfM
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Requesting upstream with range: none
Feb 08 13:46:51 rivo volumio[3552]: STREAMING PROXY: Upstream status code: 200
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand status took 107 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand status took 110 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand status took 111 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 7 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:51 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:51 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:46:51 rivo volumio[3552]: verbose: CURRENT POSITION 0
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus stop
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 149ms
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:51 rivo volumio[3552]: info:
Feb 08 13:46:51 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand status took 44 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 38 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 26 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand status took 8 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand status took 6 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:51 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:51 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:46:51 rivo volumio[3552]: verbose: CURRENT POSITION 0
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:46:51 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:51 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:51 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:46:51 rivo volumio[3552]: verbose: CURRENT POSITION 0
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:46:51 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:51 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 273ms
Feb 08 13:46:51 rivo volumio[3552]: info: ------------------------------ 271ms
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 124 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 121 milliseconds
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:51 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:51 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:51 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:51 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1365 Kbps","isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:46:51 rivo volumio[3552]: verbose: CURRENT POSITION 0
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:46:51 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:46:51 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:51 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:52 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:52 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:52 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":779,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4890 Kbps","isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:46:52 rivo volumio[3552]: verbose: CURRENT POSITION 0
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:46:52 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:52 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:52 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:52 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":779,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4890 Kbps","isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:46:52 rivo volumio[3552]: verbose: CURRENT POSITION 0
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:46:52 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:52 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:52 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:52 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:52 rivo volumio[3552]: info: ------------------------------ 380ms
Feb 08 13:46:52 rivo volumio[3552]: info: ------------------------------ 344ms
Feb 08 13:46:52 rivo volumio[3552]: info: ------------------------------ 342ms
Feb 08 13:46:52 rivo volumio[3552]: info: Signalling Playback active due to playback status change
Feb 08 13:46:52 rivo volumio[3552]: info: MCU Signalled Playback Active
Feb 08 13:46:52 rivo volumio[3552]: STREAMING PROXY: Resuming with range: bytes=442-
Feb 08 13:46:52 rivo volumio[3552]: STREAMING PROXY: Resume upstream status code: 206
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:46:53 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:46:53 rivo volumio[3552]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.49 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:46:53 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:46:53 rivo volumio[3552]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.49 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 13:46:53 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 13:46:57 rivo volumio[3552]: STREAMING PROXY: Upstream closed. Received 119408864 bytes
Feb 08 13:46:57 rivo volumio[3552]: STREAMING PROXY: Client closed connection
Feb 08 13:46:57 rivo volumio[3552]: STREAMING PROXY: Resume ended. Received 119408422 bytes
Feb 08 13:46:59 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::ClearQueue
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::stop
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::stPlaybackTimer
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::updateTrackBlock
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrackBlock
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:59 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:59 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::serviceStop
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 0
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::serviceStop
Feb 08 13:46:59 rivo volumio[3552]: info: [1770554819756] ControllerQobuz::stop
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 08 13:46:59 rivo volumio[3552]: info: ControllerMpd::stop
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand stop
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::clearPlayQueue
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::saveQueue
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioPushQueue
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::addQueueItems
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::addQueueItems
Feb 08 13:46:59 rivo volumio[3552]: info: Preload queue cleared
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/279505686
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/279505686
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/51009968
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/51009968
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/142217175
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/142217175
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/108762808
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/108762808
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/133752157
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/133752157
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/131558998
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/131558998
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/502623
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/502623
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/44541858
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/44541858
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/46264840
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/46264840
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/26381282
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/26381282
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/148665879
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/148665879
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/95575679
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/95575679
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/145929344
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/145929344
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://song/7785305
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://song/7785305
Feb 08 13:46:59 rivo volumio[3552]: info: Adding Item to queue: qobuz://album/liiwmytrukvyc
Feb 08 13:46:59 rivo volumio[3552]: info: Using cached record of: qobuz://album/liiwmytrukvyc
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioPushQueue
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::saveQueue
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::updateTrackBlock
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrackBlock
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioPlay
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::play index 14
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::stop
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::play index undefined
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::startPlaybackTimer
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: [1770554819811] ControllerQobuz::clearAddPlayTrack
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand stop
Feb 08 13:46:59 rivo volumio[3552]: info:
Feb 08 13:46:59 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand stop took 112 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand stop took 58 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand clear
Feb 08 13:46:59 rivo volumio[3552]: info:
Feb 08 13:46:59 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:59 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:59 rivo volumio[3552]: info:
Feb 08 13:46:59 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:46:59 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:46:59 rivo volumio[3552]: info:
Feb 08 13:46:59 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:59 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand status took 16 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand clear took 15 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand status took 13 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand status took 6 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/138670640"
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:46:59 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:59 rivo volumio[3552]: STREAMING PROXY: Handling url /?data=qobuz://song/138670640
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:59 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd
Feb 08 13:46:59 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:46:59 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:46:59 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:46:59 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:46:59 rivo volumio[3552]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd
Feb 08 13:46:59 rivo volumio[3552]: info: ------------------------------ 75ms
Feb 08 13:46:59 rivo volumio[3552]: info: ------------------------------ 71ms
Feb 08 13:46:59 rivo volumio[3552]: info:
Feb 08 13:46:59 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:59 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:59 rivo volumio[3552]: info:
Feb 08 13:46:59 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:46:59 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:46:59 rivo volumio[3552]: error: updateQueue error: null
Feb 08 13:46:59 rivo volumio[3552]: info: ------------------------------ 75ms
Feb 08 13:46:59 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 70 milliseconds
Feb 08 13:46:59 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:46:59 rivo volumio[3552]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Feb 08 13:46:59 rivo volumio[3552]: info: ------------------------------ 95ms
Feb 08 13:46:59 rivo volumio[3552]: info: MCU Signalled Playback Inactive
Feb 08 13:46:59 rivo volumio[3552]: info: Executing endpoint getStreamUrlqobuz
Feb 08 13:46:59 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Feb 08 13:47:00 rivo volumio[3552]: info: getStreamUrl took 222 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2302212&eid=138670640&fmt=27&profile=raw&app_id=539451548&cid=2307704&etsp=1770558420&hmac=WErKmfwHq0lRcWyoIIV7Rlqtooc
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Requesting upstream with range: none
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Upstream status code: 200
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Client closed connection
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Upstream closed. Received 15707 bytes
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Incomplete transfer. Expected 119408864, got 15707
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Retrying with range: bytes=15707- (attempt 1)
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138670640"
Feb 08 13:47:00 rivo volumio[3552]: error: updateQueue error: null
Feb 08 13:47:00 rivo volumio[3552]: error: updateQueue error: null
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:47:00 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 341ms
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 339ms
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138670640" took 4 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand play
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Handling url /?data=qobuz://song/138670640
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:47:00 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces system playlist update
Feb 08 13:47:00 rivo volumio[3552]: info: Ignoring MPD Status Update
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 20ms
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand play took 18 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 14ms
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 12ms
Feb 08 13:47:00 rivo volumio[3552]: info: Executing endpoint getStreamUrlqobuz
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Feb 08 13:47:00 rivo volumio[3552]: info: getStreamUrl took 191 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2302212&eid=138670640&fmt=27&profile=raw&app_id=539451548&cid=2307704&etsp=1770558420&hmac=WErKmfwHq0lRcWyoIIV7Rlqtooc
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Requesting upstream with range: none
Feb 08 13:47:00 rivo volumio[3552]: STREAMING PROXY: Upstream status code: 200
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand status took 9 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand status took 32 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand status took 30 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 28 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:00 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:00 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:00 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus stop
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 46ms
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:00 rivo volumio[3552]: info:
Feb 08 13:47:00 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand status took 16 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 13 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 13 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand status took 6 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand status took 5 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:00 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:00 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:00 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:00 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:00 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:00 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:00 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:00 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 167ms
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 165ms
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 116 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 115 milliseconds
Feb 08 13:47:00 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:00 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:00 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:00 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:00 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:00 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:00 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:00 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:00 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:00 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:00 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:00 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:00 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:00 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:00 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 351ms
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 341ms
Feb 08 13:47:00 rivo volumio[3552]: info: ------------------------------ 341ms
Feb 08 13:47:01 rivo volumio[3552]: info: Signalling Playback active due to playback status change
Feb 08 13:47:01 rivo volumio[3552]: info: MCU Signalled Playback Active
Feb 08 13:47:01 rivo volumio[3552]: STREAMING PROXY: Resuming with range: bytes=15707-
Feb 08 13:47:01 rivo volumio[3552]: STREAMING PROXY: Resume upstream status code: 206
Feb 08 13:47:05 rivo volumio[3552]: STREAMING PROXY: Upstream closed. Received 119408864 bytes
Feb 08 13:47:05 rivo volumio[3552]: STREAMING PROXY: Client closed connection
Feb 08 13:47:05 rivo volumio[3552]: info: CoreCommandRouter::volumioSeek
Feb 08 13:47:05 rivo volumio[3552]: info: CoreStateMachine::seek
Feb 08 13:47:05 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:05 rivo volumio[3552]: info: TRACKBLOCK {"album":"Johann Sebastian Bach: Toccata e Fuga in re minore BWV 565 (The Arte of The Flute Alone)","albumUri":"qobuz://album/liiwmytrukvyc","albumart":"https://static.qobuz.com/images/covers/yc/kv/liiwmytrukvyc_600.jpg","artist":"Claudio Ferrarini","artistUri":"qobuz://artist/81645","audioQuality":"HI_RES","duration":213,"explicit":false,"name":"Toccata and Fugue in D Minor, BWV 565: Toccata","service":"qobuz","title":"Toccata and Fugue in D Minor, BWV 565: Toccata","tracknumber":1,"type":"track","uri":"qobuz://song/138670640","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"}
Feb 08 13:47:05 rivo volumio[3552]: info: CoreStateMachine::startPlaybackTimer
Feb 08 13:47:05 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:05 rivo volumio[3552]: info: [1770554825998] ControllerQobuz::seek
Feb 08 13:47:05 rivo volumio[3552]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::seek
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: error: null
Feb 08 13:47:06 rivo volumio[3552]: info:
Feb 08 13:47:06 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:06 rivo volumio[3552]: info:
Feb 08 13:47:06 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:06 rivo volumio[3552]: info:
Feb 08 13:47:06 rivo volumio[3552]: ---------------------------- MPD announces state update: player
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::getState
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand status
Feb 08 13:47:06 rivo volumio[3552]: info: sendMpdCommand status took 22 milliseconds
Feb 08 13:47:06 rivo volumio[3552]: info: sendMpdCommand status took 19 milliseconds
Feb 08 13:47:06 rivo volumio[3552]: info: sendMpdCommand status took 17 milliseconds
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::parseState
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 08 13:47:06 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 8 milliseconds
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:06 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:06 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45540,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4784 Kbps","isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:06 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:06 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:06 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:06 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45582,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4806 Kbps","isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:06 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:06 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: info: ------------------------------ 126ms
Feb 08 13:47:06 rivo volumio[3552]: info: ------------------------------ 124ms
Feb 08 13:47:06 rivo volumio[3552]: info: sendMpdCommand playlistinfo took 102 milliseconds
Feb 08 13:47:06 rivo volumio[3552]: verbose: ControllerMpd::parseTrackInfo
Feb 08 13:47:06 rivo volumio[3552]: info: ControllerMpd::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::servicePushState
Feb 08 13:47:06 rivo volumio[3552]: info: CorePlayQueue::getTrack 14
Feb 08 13:47:06 rivo volumio[3552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45585,"duration":213,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4806 Kbps","isStreaming":false,"title":"138670640","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138670640","trackType":"qobuz"}
Feb 08 13:47:06 rivo volumio[3552]: verbose: CURRENT POSITION 14
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::syncState stateService play
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::syncState currentStatus play
Feb 08 13:47:06 rivo volumio[3552]: info: Received an update from plugin. extracting info from payload
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: info: CoreStateMachine::pushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioPushState
Feb 08 13:47:06 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output update for this device
Feb 08 13:47:06 rivo volumio[3552]: info: MRS: Pushing multiroomSync output
Feb 08 13:47:06 rivo volumio[3552]: info: ------------------------------ 173ms
Feb 08 13:47:06 rivo volumio[3552]: STREAMING PROXY: Resume ended. Received 119393157 bytes
Feb 08 13:47:18 rivo volumio5-onboarding[4450]: time=2026-02-08T13:47:18.076+01:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.49:60734,192.168.1.49:43004 @ 0x20728a0" latency=-1.490514716s timeout=10s from=APP_PAGE_ROOT
Feb 08 13:47:18 rivo sudo[5264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 08 13:47:18 rivo sudo[5264]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 08 13:47:18 rivo sudo[5264]: pam_unix(sudo:session): session closed for user root
Feb 08 13:47:18 rivo sudo[5266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 08 13:47:18 rivo sudo[5266]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 08 13:47:18 rivo sudo[5266]: pam_unix(sudo:session): session closed for user root
Feb 08 13:47:18 rivo volumio[3552]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.49 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Feb 08 13:47:19 rivo sudo[5274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 08 13:47:19 rivo sudo[5274]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 08 13:47:19 rivo sudo[5274]: pam_unix(sudo:session): session closed for user root
Feb 08 13:47:19 rivo sudo[5276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 08 13:47:19 rivo sudo[5276]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 08 13:47:19 rivo sudo[5276]: pam_unix(sudo:session): session closed for user root
Feb 08 13:47:19 rivo volumio[3552]: verbose: New Socket.io Connection to 192.168.1.29 from 192.168.1.49 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 08 13:47:19 rivo volumio[3552]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Feb 08 13:47:19 rivo volumio[3552]: info: Received Get System Info
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:47:19 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:19 rivo volumio[3552]: info: Listing playlists
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 08 13:47:19 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Feb 08 13:47:20 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 13:47:21 rivo volumio[3552]: info: Received Get System Info
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:47:21 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::volumioGetQueue
Feb 08 13:47:21 rivo volumio[3552]: info: CoreStateMachine::getQueue
Feb 08 13:47:21 rivo volumio[3552]: info: CorePlayQueue::getQueue
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 13:47:21 rivo volumio[3552]: info: Received Get System Info
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:47:21 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:21 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:47:29 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:47:33 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:47:33 rivo volumio[3552]: verbose: New Socket.io Connection to 192.168.1.29:3000 from 192.168.1.49 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:47:33 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:47:33 rivo volumio[3552]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.49 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 13:47:33 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 08 13:47:37 rivo volumio[3552]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Feb 08 13:47:37 rivo volumio[3552]: info: Received Get System Version
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 08 13:47:37 rivo volumio[3552]: info: Received Get System Info
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:47:37 rivo volumio[3552]: info: Discovery: Getting this device information
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::volumioGetState
Feb 08 13:47:37 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:47:43 rivo volumio[3552]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch
Feb 08 13:47:43 rivo volumio[3552]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 08 13:47:43 rivo volumio[3552]: TypeError: Cannot read property 'then' of undefined
Feb 08 13:47:43 rivo volumio[3552]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20)
Feb 08 13:47:43 rivo volumio[3552]: at Socket.emit (events.js:400:28)
Feb 08 13:47:43 rivo volumio[3552]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Feb 08 13:47:43 rivo volumio[3552]: at processTicksAndRejections (internal/process/task_queues.js:77:11)
Feb 08 13:47:43 rivo volumio[3552]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 08 13:47:44 rivo sudo[5373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-08 13:46
Feb 08 13:47:44 rivo sudo[5373]: 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"