-- 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"