-- Logs begin at Fri 2024-07-26 17:27:02 UTC, end at Fri 2024-07-26 17:45:57 UTC. --
Jul 26 17:44:00 rivo volumio[3265]: info: handleBrowseUri took 723 milliseconds
Jul 26 17:44:00 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956198
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956199
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956200
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956203
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956205
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956207
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956209
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956212
Jul 26 17:44:00 rivo volumio[3265]: info: Preloading song: qobuz://song/20956214
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956198 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956199 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956200 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956203 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Executing endpoint getSimilarAlbums
Jul 26 17:44:00 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956205 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:44:00 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:44:00 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:44:00 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956207 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: explodeUri took 237 milliseconds
Jul 26 17:44:00 rivo volumio[3265]: info: explodeUri took 302 milliseconds
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956209 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956212 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: Exploding uri qobuz://song/20956214 in service qobuz
Jul 26 17:44:00 rivo volumio[3265]: info: explodeUri took 321 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: info: explodeUri took 250 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: info: explodeUri took 378 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: info: explodeUri took 247 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: info: explodeUri took 361 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: info: explodeUri took 248 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: info: explodeUri took 247 milliseconds
Jul 26 17:44:01 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:44:04 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:44:09 rivo volumio[3265]: info: CoreCommandRouter::volumioplayNextItems
Jul 26 17:44:09 rivo volumio[3265]: info: CoreStateMachine::playNextItems
Jul 26 17:44:09 rivo volumio[3265]: info: CorePlayQueue::addPlayNextItems
Jul 26 17:44:09 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:44:09 rivo volumio[3265]: info: Adding Item to play next: qobuz://album/0656605211565
Jul 26 17:44:09 rivo volumio[3265]: info: Exploding uri qobuz://album/0656605211565 in service qobuz
Jul 26 17:44:09 rivo volumio[3265]: info: explodeUri took 134 milliseconds
Jul 26 17:44:09 rivo volumio[3265]: info: CoreCommandRouter::volumioPushQueue
Jul 26 17:44:09 rivo volumio[3265]: info: CorePlayQueue::saveQueue
Jul 26 17:44:09 rivo volumio[3265]: info: CoreStateMachine::updateTrackBlock
Jul 26 17:44:09 rivo volumio[3265]: info: CorePlayQueue::getTrackBlock
Jul 26 17:44:22 rivo ntpd[4205]: 140.238.174.86 local addr 192.168.1.127 ->
Jul 26 17:44:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 26 17:44:39 rivo volumio[3265]: info: handleBrowseUri took 229 milliseconds
Jul 26 17:44:39 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137271
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137272
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137273
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137274
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137275
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137276
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137277
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137278
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137279
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137280
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137281
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137282
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137283
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137284
Jul 26 17:44:39 rivo volumio[3265]: info: Preloading song: qobuz://song/37137285
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137271 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137272 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137273 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: Executing endpoint getSimilarAlbums
Jul 26 17:44:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137274 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:44:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:44:39 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:44:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137275 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: explodeUri took 254 milliseconds
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137276 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: explodeUri took 247 milliseconds
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137277 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: explodeUri took 235 milliseconds
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137278 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: explodeUri took 246 milliseconds
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137279 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: Exploding uri qobuz://song/37137280 in service qobuz
Jul 26 17:44:39 rivo volumio[3265]: info: explodeUri took 267 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: Exploding uri qobuz://song/37137281 in service qobuz
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 288 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: Exploding uri qobuz://song/37137282 in service qobuz
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 258 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 235 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: Exploding uri qobuz://song/37137283 in service qobuz
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 226 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: Exploding uri qobuz://song/37137284 in service qobuz
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 236 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: Exploding uri qobuz://song/37137285 in service qobuz
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 296 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 289 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 330 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 285 milliseconds
Jul 26 17:44:40 rivo volumio[3265]: info: explodeUri took 309 milliseconds
Jul 26 17:44:43 rivo volumio[3265]: info: CoreCommandRouter::volumioPlay
Jul 26 17:44:43 rivo volumio[3265]: info: CoreStateMachine::play index undefined
Jul 26 17:44:43 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:44:43 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:44:43 rivo volumio[3265]: info: CoreStateMachine::startPlaybackTimer
Jul 26 17:44:43 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:44:43 rivo volumio[3265]: info: [1722015883684] ControllerQobuz::clearAddPlayTrack
Jul 26 17:44:43 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop
Jul 26 17:44:43 rivo volumio[3265]: info: sendMpdCommand stop took 1 milliseconds
Jul 26 17:44:43 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear
Jul 26 17:44:43 rivo volumio[3265]: info:
Jul 26 17:44:43 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:44:43 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:44:43 rivo volumio[3265]: info: sendMpdCommand clear took 2 milliseconds
Jul 26 17:44:43 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/2968881"
Jul 26 17:44:43 rivo volumio[3265]: error: updateQueue error: null
Jul 26 17:44:43 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:44:43 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/2968881
Jul 26 17:44:43 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:44:43 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:44:43 rivo volumio[3265]: info: getStreamUrl took 177 milliseconds
Jul 26 17:44:43 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=2968881&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019483&hmac=85EUJXOebk3bu3w-XZiXI30GOJM
Jul 26 17:44:43 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:44:43 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:44:43 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2968881"
Jul 26 17:44:43 rivo volumio[3265]: info:
Jul 26 17:44:43 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:44:43 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:44:43 rivo volumio[3265]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/2968881" took 2 milliseconds
Jul 26 17:44:43 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 26 17:44:43 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand play
Jul 26 17:44:43 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:44:43 rivo volumio[3265]: info: sendMpdCommand play took 3 milliseconds
Jul 26 17:44:43 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/2968881
Jul 26 17:44:43 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:44:43 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:44:44 rivo volumio[3265]: info: getStreamUrl took 192 milliseconds
Jul 26 17:44:44 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=2968881&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019484&hmac=jD0EhW2F8wic0zz0AWXccYW97l4
Jul 26 17:44:44 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:44:44 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:44:44 rivo volumio[3265]: info:
Jul 26 17:44:44 rivo volumio[3265]: ---------------------------- MPD announces state update: player
Jul 26 17:44:44 rivo volumio[3265]: info: ControllerMpd::getState
Jul 26 17:44:44 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand status
Jul 26 17:44:44 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clearerror
Jul 26 17:44:44 rivo volumio[3265]: info: sendMpdCommand status took 7 milliseconds
Jul 26 17:44:44 rivo volumio[3265]: verbose: ControllerMpd::parseState
Jul 26 17:44:44 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 26 17:44:44 rivo volumio[3265]: info: sendMpdCommand clearerror took 4 milliseconds
Jul 26 17:44:44 rivo volumio[3265]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 26 17:44:44 rivo volumio[3265]: verbose: ControllerMpd::parseTrackInfo
Jul 26 17:44:44 rivo volumio[3265]: info: ControllerMpd::pushState
Jul 26 17:44:44 rivo volumio[3265]: info: CoreCommandRouter::servicePushState
Jul 26 17:44:44 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:44:44 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 26 17:44:44 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:44:44 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:44:44 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:44:44 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:44:44 rivo volumio[3265]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2968881","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/2968881","trackType":"qobuz"}
Jul 26 17:44:44 rivo volumio[3265]: verbose: CURRENT POSITION 0
Jul 26 17:44:44 rivo volumio[3265]: info: CoreStateMachine::syncState stateService stop
Jul 26 17:44:44 rivo volumio[3265]: info: CoreStateMachine::syncState currentStatus stop
Jul 26 17:44:44 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:44:44 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:44:44 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:44:44 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:44:44 rivo volumio[3265]: info: No code
Jul 26 17:44:44 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:44:44 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:44:44 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:44:44 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:44:44 rivo volumio[3265]: info: ------------------------------ 72ms
Jul 26 17:44:44 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:44:44 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:44:44 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:44:44 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:44:46 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:44:46 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:44:46 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:44:46 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:44:53 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:44:57 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:44:57 rivo volumio[3265]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::ClearQueue
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::stop
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::clearPlayQueue
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::saveQueue
Jul 26 17:44:57 rivo volumio[3265]: info: CoreCommandRouter::volumioPushQueue
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::addQueueItems
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::addQueueItems
Jul 26 17:44:57 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137271
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137271
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137272
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137272
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137273
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137273
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137274
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137274
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137275
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137275
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137276
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137276
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137277
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137277
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137278
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137278
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137279
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137279
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137280
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137280
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137281
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137281
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137282
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137282
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137283
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137283
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137284
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137284
Jul 26 17:44:57 rivo volumio[3265]: info: Adding Item to queue: qobuz://song/37137285
Jul 26 17:44:57 rivo volumio[3265]: info: Using cached record of: qobuz://song/37137285
Jul 26 17:44:57 rivo volumio[3265]: info: CoreCommandRouter::volumioPushQueue
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::saveQueue
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::updateTrackBlock
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::getTrackBlock
Jul 26 17:44:57 rivo volumio[3265]: info: CoreCommandRouter::volumioPlay
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::play index 0
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::stop
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::play index undefined
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:44:57 rivo volumio[3265]: info: CoreStateMachine::startPlaybackTimer
Jul 26 17:44:57 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:44:57 rivo volumio[3265]: info: [1722015897911] ControllerQobuz::clearAddPlayTrack
Jul 26 17:44:57 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop
Jul 26 17:44:57 rivo volumio[3265]: info: sendMpdCommand stop took 5 milliseconds
Jul 26 17:44:57 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear
Jul 26 17:44:57 rivo volumio[3265]: info:
Jul 26 17:44:57 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:44:57 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:44:57 rivo volumio[3265]: info: sendMpdCommand clear took 5 milliseconds
Jul 26 17:44:57 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:44:57 rivo volumio[3265]: error: updateQueue error: null
Jul 26 17:44:57 rivo volumio[3265]: info: ------------------------------ 7ms
Jul 26 17:44:57 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:44:57 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:44:57 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:44:58 rivo volumio[3265]: info: getStreamUrl took 792 milliseconds
Jul 26 17:44:58 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019498&hmac=uZYiJ83UDUAjH0Oyo6lz2pIylhY
Jul 26 17:44:58 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:44:58 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:44:58 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:44:58 rivo volumio[3265]: info:
Jul 26 17:44:58 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:44:58 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:44:58 rivo volumio[3265]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271" took 2 milliseconds
Jul 26 17:44:58 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 26 17:44:58 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand play
Jul 26 17:44:58 rivo volumio[3265]: info: ------------------------------ 5ms
Jul 26 17:44:58 rivo volumio[3265]: info: sendMpdCommand play took 2 milliseconds
Jul 26 17:44:58 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:44:58 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:44:58 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:44:59 rivo volumio[3265]: info: getStreamUrl took 240 milliseconds
Jul 26 17:44:59 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019498&hmac=uZYiJ83UDUAjH0Oyo6lz2pIylhY
Jul 26 17:44:59 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:44:59 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:44:59 rivo volumio[3265]: info:
Jul 26 17:44:59 rivo volumio[3265]: ---------------------------- MPD announces state update: player
Jul 26 17:44:59 rivo volumio[3265]: info: ControllerMpd::getState
Jul 26 17:44:59 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand status
Jul 26 17:44:59 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clearerror
Jul 26 17:44:59 rivo volumio[3265]: info: sendMpdCommand status took 7 milliseconds
Jul 26 17:44:59 rivo volumio[3265]: verbose: ControllerMpd::parseState
Jul 26 17:44:59 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 26 17:44:59 rivo volumio[3265]: info: sendMpdCommand clearerror took 10 milliseconds
Jul 26 17:44:59 rivo volumio[3265]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 26 17:44:59 rivo volumio[3265]: verbose: ControllerMpd::parseTrackInfo
Jul 26 17:44:59 rivo volumio[3265]: info: ControllerMpd::pushState
Jul 26 17:44:59 rivo volumio[3265]: info: CoreCommandRouter::servicePushState
Jul 26 17:44:59 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:44:59 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 26 17:44:59 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:44:59 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:44:59 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:44:59 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:44:59 rivo volumio[3265]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37137271","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/37137271","trackType":"qobuz"}
Jul 26 17:44:59 rivo volumio[3265]: verbose: CURRENT POSITION 0
Jul 26 17:44:59 rivo volumio[3265]: info: CoreStateMachine::syncState stateService stop
Jul 26 17:44:59 rivo volumio[3265]: info: CoreStateMachine::syncState currentStatus stop
Jul 26 17:44:59 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:44:59 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:44:59 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:44:59 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:44:59 rivo volumio[3265]: info: No code
Jul 26 17:44:59 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:44:59 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:44:59 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:44:59 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:44:59 rivo volumio[3265]: info: ------------------------------ 95ms
Jul 26 17:44:59 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:44:59 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:44:59 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:44:59 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:44:59 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:01 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:01 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:08 rivo volumio[3265]: info: CoreCommandRouter::volumioplayNextItems
Jul 26 17:45:08 rivo volumio[3265]: info: CoreStateMachine::playNextItems
Jul 26 17:45:08 rivo volumio[3265]: info: CorePlayQueue::addPlayNextItems
Jul 26 17:45:08 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:45:08 rivo volumio[3265]: info: Adding Item to play next: qobuz://album/0191018366988
Jul 26 17:45:08 rivo volumio[3265]: info: Exploding uri qobuz://album/0191018366988 in service qobuz
Jul 26 17:45:08 rivo volumio[3265]: info: explodeUri took 149 milliseconds
Jul 26 17:45:08 rivo volumio[3265]: info: CoreCommandRouter::volumioPushQueue
Jul 26 17:45:08 rivo volumio[3265]: info: CorePlayQueue::saveQueue
Jul 26 17:45:08 rivo volumio[3265]: info: CoreStateMachine::updateTrackBlock
Jul 26 17:45:08 rivo volumio[3265]: info: CorePlayQueue::getTrackBlock
Jul 26 17:45:18 rivo volumio[3265]: info: CoreCommandRouter::volumioPlay
Jul 26 17:45:18 rivo volumio[3265]: info: CoreStateMachine::play index undefined
Jul 26 17:45:18 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:45:18 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:18 rivo volumio[3265]: info: CoreStateMachine::startPlaybackTimer
Jul 26 17:45:18 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:18 rivo volumio[3265]: info: [1722015918555] ControllerQobuz::clearAddPlayTrack
Jul 26 17:45:18 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop
Jul 26 17:45:18 rivo volumio[3265]: info: sendMpdCommand stop took 2 milliseconds
Jul 26 17:45:18 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear
Jul 26 17:45:18 rivo volumio[3265]: info:
Jul 26 17:45:18 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:18 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:18 rivo volumio[3265]: info: sendMpdCommand clear took 2 milliseconds
Jul 26 17:45:18 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:18 rivo volumio[3265]: error: updateQueue error: null
Jul 26 17:45:18 rivo volumio[3265]: info: ------------------------------ 3ms
Jul 26 17:45:18 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:18 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:18 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:18 rivo volumio[3265]: info: getStreamUrl took 184 milliseconds
Jul 26 17:45:18 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019518&hmac=fgb3TTq6ZKST6CQyR-M8v3lp7-0
Jul 26 17:45:18 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:18 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:18 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:18 rivo volumio[3265]: info:
Jul 26 17:45:18 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:18 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:18 rivo volumio[3265]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271" took 2 milliseconds
Jul 26 17:45:18 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 26 17:45:18 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand play
Jul 26 17:45:18 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:45:18 rivo volumio[3265]: info: sendMpdCommand play took 2 milliseconds
Jul 26 17:45:18 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:18 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:18 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:19 rivo volumio[3265]: info: getStreamUrl took 198 milliseconds
Jul 26 17:45:19 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019518&hmac=fgb3TTq6ZKST6CQyR-M8v3lp7-0
Jul 26 17:45:19 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:19 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:19 rivo volumio[3265]: info:
Jul 26 17:45:19 rivo volumio[3265]: ---------------------------- MPD announces state update: player
Jul 26 17:45:19 rivo volumio[3265]: info: ControllerMpd::getState
Jul 26 17:45:19 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand status
Jul 26 17:45:19 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clearerror
Jul 26 17:45:19 rivo volumio[3265]: info: sendMpdCommand status took 8 milliseconds
Jul 26 17:45:19 rivo volumio[3265]: verbose: ControllerMpd::parseState
Jul 26 17:45:19 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 26 17:45:19 rivo volumio[3265]: info: sendMpdCommand clearerror took 7 milliseconds
Jul 26 17:45:19 rivo volumio[3265]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 26 17:45:19 rivo volumio[3265]: verbose: ControllerMpd::parseTrackInfo
Jul 26 17:45:19 rivo volumio[3265]: info: ControllerMpd::pushState
Jul 26 17:45:19 rivo volumio[3265]: info: CoreCommandRouter::servicePushState
Jul 26 17:45:19 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:19 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 26 17:45:19 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:19 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:19 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:19 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:19 rivo volumio[3265]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37137271","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/37137271","trackType":"qobuz"}
Jul 26 17:45:19 rivo volumio[3265]: verbose: CURRENT POSITION 0
Jul 26 17:45:19 rivo volumio[3265]: info: CoreStateMachine::syncState stateService stop
Jul 26 17:45:19 rivo volumio[3265]: info: CoreStateMachine::syncState currentStatus stop
Jul 26 17:45:19 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:19 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:19 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:19 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:19 rivo volumio[3265]: info: No code
Jul 26 17:45:19 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:19 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:19 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:19 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:19 rivo volumio[3265]: info: ------------------------------ 69ms
Jul 26 17:45:19 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:19 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:19 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:19 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:19 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:20 rivo volumio[3265]: info: CoreCommandRouter::volumioPlay
Jul 26 17:45:20 rivo volumio[3265]: info: CoreStateMachine::play index undefined
Jul 26 17:45:20 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:45:20 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:20 rivo volumio[3265]: info: CoreStateMachine::startPlaybackTimer
Jul 26 17:45:20 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:20 rivo volumio[3265]: info: [1722015920644] ControllerQobuz::clearAddPlayTrack
Jul 26 17:45:20 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop
Jul 26 17:45:20 rivo volumio[3265]: info: sendMpdCommand stop took 1 milliseconds
Jul 26 17:45:20 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear
Jul 26 17:45:20 rivo volumio[3265]: info:
Jul 26 17:45:20 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:20 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:20 rivo volumio[3265]: info: sendMpdCommand clear took 2 milliseconds
Jul 26 17:45:20 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:20 rivo volumio[3265]: error: updateQueue error: null
Jul 26 17:45:20 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:45:20 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:20 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:20 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:20 rivo volumio[3265]: info: getStreamUrl took 182 milliseconds
Jul 26 17:45:20 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019520&hmac=qSTYlNwuxj_c1WeQDsmUrx_MOhA
Jul 26 17:45:20 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:20 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:20 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:20 rivo volumio[3265]: info:
Jul 26 17:45:20 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:20 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:20 rivo volumio[3265]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271" took 2 milliseconds
Jul 26 17:45:20 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 26 17:45:20 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand play
Jul 26 17:45:20 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:45:20 rivo volumio[3265]: info: sendMpdCommand play took 3 milliseconds
Jul 26 17:45:20 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:20 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:20 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:21 rivo volumio[3265]: info: getStreamUrl took 191 milliseconds
Jul 26 17:45:21 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019521&hmac=RI9pPM5JytuGQ21gvWq9TvkTyDU
Jul 26 17:45:21 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:21 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:21 rivo volumio[3265]: info:
Jul 26 17:45:21 rivo volumio[3265]: ---------------------------- MPD announces state update: player
Jul 26 17:45:21 rivo volumio[3265]: info: ControllerMpd::getState
Jul 26 17:45:21 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand status
Jul 26 17:45:21 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clearerror
Jul 26 17:45:21 rivo volumio[3265]: info: sendMpdCommand status took 7 milliseconds
Jul 26 17:45:21 rivo volumio[3265]: verbose: ControllerMpd::parseState
Jul 26 17:45:21 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 26 17:45:21 rivo volumio[3265]: info: sendMpdCommand clearerror took 3 milliseconds
Jul 26 17:45:21 rivo volumio[3265]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 26 17:45:21 rivo volumio[3265]: verbose: ControllerMpd::parseTrackInfo
Jul 26 17:45:21 rivo volumio[3265]: info: ControllerMpd::pushState
Jul 26 17:45:21 rivo volumio[3265]: info: CoreCommandRouter::servicePushState
Jul 26 17:45:21 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:21 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 26 17:45:21 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:21 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:21 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:21 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:21 rivo volumio[3265]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37137271","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/37137271","trackType":"qobuz"}
Jul 26 17:45:21 rivo volumio[3265]: verbose: CURRENT POSITION 0
Jul 26 17:45:21 rivo volumio[3265]: info: CoreStateMachine::syncState stateService stop
Jul 26 17:45:21 rivo volumio[3265]: info: CoreStateMachine::syncState currentStatus stop
Jul 26 17:45:21 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:21 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:21 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:21 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:21 rivo volumio[3265]: info: No code
Jul 26 17:45:21 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:21 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:21 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:21 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:21 rivo volumio[3265]: info: ------------------------------ 61ms
Jul 26 17:45:21 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:21 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:21 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:21 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:21 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:21 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:21 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:23 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:23 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:24 rivo volumio[3265]: info: CoreCommandRouter::volumioPlay
Jul 26 17:45:24 rivo volumio[3265]: info: CoreStateMachine::play index undefined
Jul 26 17:45:24 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:45:24 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:24 rivo volumio[3265]: info: CoreStateMachine::startPlaybackTimer
Jul 26 17:45:24 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:24 rivo volumio[3265]: info: [1722015924542] ControllerQobuz::clearAddPlayTrack
Jul 26 17:45:24 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop
Jul 26 17:45:24 rivo volumio[3265]: info: sendMpdCommand stop took 1 milliseconds
Jul 26 17:45:24 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear
Jul 26 17:45:24 rivo volumio[3265]: info:
Jul 26 17:45:24 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:24 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:24 rivo volumio[3265]: info: sendMpdCommand clear took 2 milliseconds
Jul 26 17:45:24 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:24 rivo volumio[3265]: error: updateQueue error: null
Jul 26 17:45:24 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:24 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:24 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:24 rivo volumio[3265]: info: getStreamUrl took 191 milliseconds
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019524&hmac=2xDCRoujyewEnh4jirHUmk3OGWc
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:24 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:24 rivo volumio[3265]: info:
Jul 26 17:45:24 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:24 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:24 rivo volumio[3265]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271" took 2 milliseconds
Jul 26 17:45:24 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 26 17:45:24 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand play
Jul 26 17:45:24 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:45:24 rivo volumio[3265]: info: sendMpdCommand play took 2 milliseconds
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:24 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:24 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:24 rivo volumio[3265]: info: getStreamUrl took 178 milliseconds
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019524&hmac=2xDCRoujyewEnh4jirHUmk3OGWc
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:24 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:24 rivo volumio[3265]: info:
Jul 26 17:45:24 rivo volumio[3265]: ---------------------------- MPD announces state update: player
Jul 26 17:45:24 rivo volumio[3265]: info: ControllerMpd::getState
Jul 26 17:45:24 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand status
Jul 26 17:45:25 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clearerror
Jul 26 17:45:25 rivo volumio[3265]: info: sendMpdCommand status took 7 milliseconds
Jul 26 17:45:25 rivo volumio[3265]: verbose: ControllerMpd::parseState
Jul 26 17:45:25 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 26 17:45:25 rivo volumio[3265]: info: sendMpdCommand clearerror took 4 milliseconds
Jul 26 17:45:25 rivo volumio[3265]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 26 17:45:25 rivo volumio[3265]: verbose: ControllerMpd::parseTrackInfo
Jul 26 17:45:25 rivo volumio[3265]: info: ControllerMpd::pushState
Jul 26 17:45:25 rivo volumio[3265]: info: CoreCommandRouter::servicePushState
Jul 26 17:45:25 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:25 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 26 17:45:25 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:25 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:25 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:25 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:25 rivo volumio[3265]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37137271","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/37137271","trackType":"qobuz"}
Jul 26 17:45:25 rivo volumio[3265]: verbose: CURRENT POSITION 0
Jul 26 17:45:25 rivo volumio[3265]: info: CoreStateMachine::syncState stateService stop
Jul 26 17:45:25 rivo volumio[3265]: info: CoreStateMachine::syncState currentStatus stop
Jul 26 17:45:25 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:25 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:25 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:25 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:25 rivo volumio[3265]: info: No code
Jul 26 17:45:25 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:25 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:25 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:25 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:25 rivo volumio[3265]: info: ------------------------------ 72ms
Jul 26 17:45:25 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:25 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:25 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:25 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:25 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::volumioPlay
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::play index undefined
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 26 17:45:26 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::startPlaybackTimer
Jul 26 17:45:26 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:26 rivo volumio[3265]: info: [1722015926244] ControllerQobuz::clearAddPlayTrack
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand stop took 1 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear
Jul 26 17:45:26 rivo volumio[3265]: info:
Jul 26 17:45:26 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:26 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand clear took 2 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:26 rivo volumio[3265]: error: updateQueue error: null
Jul 26 17:45:26 rivo volumio[3265]: info: ------------------------------ 3ms
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:26 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:26 rivo volumio[3265]: info: getStreamUrl took 241 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019526&hmac=ojIAFY9HfvU_I5DThhxWmgZsET0
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271"
Jul 26 17:45:26 rivo volumio[3265]: info:
Jul 26 17:45:26 rivo volumio[3265]: ---------------------------- MPD announces system playlist update
Jul 26 17:45:26 rivo volumio[3265]: info: Ignoring MPD Status Update
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/37137271" took 2 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand play
Jul 26 17:45:26 rivo volumio[3265]: info: ------------------------------ 4ms
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand play took 2 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Handling url /?data=qobuz://song/37137271
Jul 26 17:45:26 rivo volumio[3265]: info: Executing endpoint getStreamUrlqobuz
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
Jul 26 17:45:26 rivo volumio[3265]: info: getStreamUrl took 191 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2673627&eid=37137271&fmt=6&profile=raw&app_id=539451548&cid=1857158&etsp=1722019526&hmac=ojIAFY9HfvU_I5DThhxWmgZsET0
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Error proxying request: Error: socket hang up
Jul 26 17:45:26 rivo volumio[3265]: STREAMING PROXY: Client dropped request, destroying
Jul 26 17:45:26 rivo volumio[3265]: info:
Jul 26 17:45:26 rivo volumio[3265]: ---------------------------- MPD announces state update: player
Jul 26 17:45:26 rivo volumio[3265]: info: ControllerMpd::getState
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand status
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand clearerror
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand status took 8 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::parseState
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand clearerror took 3 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 26 17:45:26 rivo volumio[3265]: verbose: ControllerMpd::parseTrackInfo
Jul 26 17:45:26 rivo volumio[3265]: info: ControllerMpd::pushState
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::servicePushState
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:26 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:26 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:26 rivo volumio[3265]: info: CorePlayQueue::getTrack 0
Jul 26 17:45:26 rivo volumio[3265]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37137271","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/37137271","trackType":"qobuz"}
Jul 26 17:45:26 rivo volumio[3265]: verbose: CURRENT POSITION 0
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::syncState stateService stop
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::syncState currentStatus stop
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:26 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:26 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:26 rivo volumio[3265]: info: No code
Jul 26 17:45:26 rivo volumio[3265]: info: CoreStateMachine::pushState
Jul 26 17:45:26 rivo volumio[3265]: info: CoreCommandRouter::volumioPushState
Jul 26 17:45:26 rivo volumio[3265]: info: MRS: Pushing multiroomSync output update for this device
Jul 26 17:45:26 rivo volumio[3265]: info: MRS: Pushing multiroomSync output
Jul 26 17:45:26 rivo volumio[3265]: info: ------------------------------ 59ms
Jul 26 17:45:26 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:26 rivo volumio[3265]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined
Jul 26 17:45:26 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:26 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:26 rivo volumio[3265]: info: Updating RAAT Signal Path
Jul 26 17:45:28 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:45:28 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:28 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:30 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:45:35 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:45:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 26 17:45:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 26 17:45:39 rivo volumio[3265]: info: Discovery: Getting this device information
Jul 26 17:45:39 rivo volumio[3265]: info: CoreCommandRouter::volumioGetState
Jul 26 17:45:39 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 26 17:45:44 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 26 17:45:44 rivo volumio[3265]: info: handleBrowseUri took 278 milliseconds
Jul 26 17:45:44 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016943
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016944
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016945
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016946
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016947
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016948
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016949
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016950
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016951
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016952
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016953
Jul 26 17:45:44 rivo volumio[3265]: info: Preloading song: qobuz://song/3016954
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016943 in service qobuz
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016944 in service qobuz
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016945 in service qobuz
Jul 26 17:45:44 rivo volumio[3265]: info: Executing endpoint getSimilarAlbums
Jul 26 17:45:44 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016946 in service qobuz
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016947 in service qobuz
Jul 26 17:45:44 rivo volumio[3265]: info: explodeUri took 230 milliseconds
Jul 26 17:45:44 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:44 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:44 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:44 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016948 in service qobuz
Jul 26 17:45:44 rivo volumio[3265]: info: Exploding uri qobuz://song/3016949 in service qobuz
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 265 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 246 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: Exploding uri qobuz://song/3016950 in service qobuz
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 215 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: Exploding uri qobuz://song/3016951 in service qobuz
Jul 26 17:45:45 rivo volumio[3265]: info: Exploding uri qobuz://song/3016952 in service qobuz
Jul 26 17:45:45 rivo volumio[3265]: info: Exploding uri qobuz://song/3016953 in service qobuz
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 336 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: Exploding uri qobuz://song/3016954 in service qobuz
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 270 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 277 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 251 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 279 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 229 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 290 milliseconds
Jul 26 17:45:45 rivo volumio[3265]: info: explodeUri took 284 milliseconds
Jul 26 17:45:49 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: mpd , search
Jul 26 17:45:49 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , search
Jul 26 17:45:49 rivo volumio[3265]: info: searchQOBUZUri took 931 milliseconds
Jul 26 17:45:49 rivo volumio[3265]: info: All search sources collected, pushing search results
Jul 26 17:45:49 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 26 17:45:50 rivo volumio[3265]: info: handleBrowseUri took 298 milliseconds
Jul 26 17:45:50 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:45:50 rivo volumio[3265]: info: Executing endpoint getSimilarArtists
Jul 26 17:45:50 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists
Jul 26 17:45:50 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:50 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:52 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:45:52 rivo volumio[3265]: error: Failed request for metavolumio API
Jul 26 17:45:53 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
Jul 26 17:45:53 rivo volumio[3265]: info: handleBrowseUri took 141 milliseconds
Jul 26 17:45:53 rivo volumio[3265]: info: Preload queue cleared
Jul 26 17:45:54 rivo volumio[3265]: info: Executing endpoint getSimilarAlbums
Jul 26 17:45:54 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
Jul 26 17:45:54 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:54 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:54 rivo volumio[3265]: info: Executing endpoint metavolumio
Jul 26 17:45:54 rivo volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Jul 26 17:45:57 rivo volumio[3265]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 26 17:45:57 rivo volumio[3265]: Error: socket hang up
Jul 26 17:45:57 rivo volumio[3265]: at connResetException (internal/errors.js:639:14)
Jul 26 17:45:57 rivo volumio[3265]: at Socket.socketOnEnd (_http_client.js:499:23)
Jul 26 17:45:57 rivo volumio[3265]: at Socket.emit (events.js:412:35)
Jul 26 17:45:57 rivo volumio[3265]: at endReadableNT (internal/streams/readable.js:1333:12)
Jul 26 17:45:57 rivo volumio[3265]: at processTicksAndRejections (internal/process/task_queues.js:82:21) {
Jul 26 17:45:57 rivo volumio[3265]: code: 'ECONNRESET'
Jul 26 17:45:57 rivo volumio[3265]: }
Jul 26 17:45:57 rivo volumio[3265]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 26 17:45:57 rivo sudo[6061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 17:44
Jul 26 17:45:57 rivo sudo[6061]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="rivo"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:27:25 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="mp1"
VOLUMIO_DEVICENAME="Volumio MP1"
VOLUMIO_VENDOR_MODEL="Volumio Rivo"
VOLUMIO_VENDOR="Volumio"
VOLUMIO_MODEL="Rivo"
VOLUMIO_HASH="891b8adfc94c7cc44424972b60e8e09c"