-- Logs begin at Sun 2025-03-16 20:25:41 CET, end at Wed 2025-03-19 18:19:24 CET. -- Mar 19 18:18:08 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:18:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:18:08 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:18:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:18:17 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:18:17 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:18:17 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:18:17 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:18:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 19 18:18:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 19 18:18:18 volumio volumio[1100]: info: Discovery: Getting this device information Mar 19 18:18:18 volumio volumio[1100]: info: CoreCommandRouter::volumioGetState Mar 19 18:18:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 19 18:18:45 volumio volumio[1100]: info: VolumeController::SetAlsaVolume61 Mar 19 18:18:45 volumio volumio[1100]: info: CoreCommandRouter::volumioGetState Mar 19 18:18:45 volumio volumio[1100]: info: CoreStateMachine::pushState Mar 19 18:18:45 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:18:45 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState Mar 19 18:18:45 volumio volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Mar 19 18:18:45 volumio volumio[1100]: info: MRS: Pushing multiroomSync output Mar 19 18:18:48 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 19 18:18:48 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 19 18:18:48 volumio volumio[1100]: info: Discovery: Getting this device information Mar 19 18:18:48 volumio volumio[1100]: info: CoreCommandRouter::volumioGetState Mar 19 18:18:48 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 19 18:19:01 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 19 18:19:02 volumio volumio[1100]: info: handleBrowseUri took 588 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: Preload queue cleared Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618637 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618638 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618639 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618640 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618641 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618642 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618643 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618644 Mar 19 18:19:02 volumio volumio[1100]: info: Preloading song: qobuz://song/51618645 Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618637 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618638 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618639 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618640 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618641 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618642 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618643 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618644 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: Exploding uri qobuz://song/51618645 in service qobuz Mar 19 18:19:02 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 506 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 540 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 516 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 522 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 681 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 572 milliseconds Mar 19 18:19:02 volumio volumio[1100]: info: explodeUri took 491 milliseconds Mar 19 18:19:03 volumio volumio[1100]: info: explodeUri took 479 milliseconds Mar 19 18:19:03 volumio volumio[1100]: info: explodeUri took 591 milliseconds Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:03 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:03 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:04 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:04 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:04 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:04 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:04 volumio volumio[1100]: info: Preload queue cleared Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::ClearQueue Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::stop Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::stPlaybackTimer Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::pushState Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState Mar 19 18:19:04 volumio volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Mar 19 18:19:04 volumio volumio[1100]: info: MRS: Pushing multiroomSync output Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::serviceStop Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::serviceStop Mar 19 18:19:04 volumio volumio[1100]: info: [1742404744843] ControllerQobuz::stop Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 18:19:04 volumio volumio[1100]: info: ControllerMpd::stop Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::clearPlayQueue Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::saveQueue Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::addQueueItems Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::addQueueItems Mar 19 18:19:04 volumio volumio[1100]: info: Preload queue cleared Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618637 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618637 Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::saveQueue Mar 19 18:19:04 volumio volumio[1100]: STREAMING PROXY: Client dropped request, destroying Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::volumioPlay Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::play index 0 Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::addQueueItems Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::addQueueItems Mar 19 18:19:04 volumio volumio[1100]: info: Preload queue cleared Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618638 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618638 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618639 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618639 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618640 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618640 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618641 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618641 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618642 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618642 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618643 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618643 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618644 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618644 Mar 19 18:19:04 volumio volumio[1100]: info: Adding Item to queue: qobuz://song/51618645 Mar 19 18:19:04 volumio volumio[1100]: info: Using cached record of: qobuz://song/51618645 Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::stop Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::saveQueue Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::play index undefined Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 Mar 19 18:19:04 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer Mar 19 18:19:04 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 Mar 19 18:19:04 volumio volumio[1100]: info: [1742404744865] ControllerQobuz::clearAddPlayTrack Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop Mar 19 18:19:04 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:04 volumio volumio[1100]: info: Mar 19 18:19:04 volumio volumio[1100]: ---------------------------- MPD announces state update: player Mar 19 18:19:04 volumio volumio[1100]: info: sendMpdCommand stop took 32 milliseconds Mar 19 18:19:04 volumio volumio[1100]: info: ControllerMpd::getState Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:19:04 volumio volumio[1100]: info: sendMpdCommand stop took 10 milliseconds Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear Mar 19 18:19:04 volumio volumio[1100]: info: Mar 19 18:19:04 volumio volumio[1100]: ---------------------------- MPD announces system playlist update Mar 19 18:19:04 volumio volumio[1100]: info: Ignoring MPD Status Update Mar 19 18:19:04 volumio volumio[1100]: info: sendMpdCommand status took 4 milliseconds Mar 19 18:19:04 volumio volumio[1100]: info: sendMpdCommand clear took 5 milliseconds Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::parseState Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/51618637" Mar 19 18:19:04 volumio volumio[1100]: error: updateQueue error: null Mar 19 18:19:04 volumio volumio[1100]: info: ------------------------------ 4ms Mar 19 18:19:04 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 19 18:19:04 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:19:04 volumio volumio[1100]: STREAMING PROXY: Handling url /?data=qobuz://song/51618637 Mar 19 18:19:04 volumio volumio[1100]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 19 18:19:04 volumio volumio[1100]: info: ------------------------------ 11ms Mar 19 18:19:04 volumio volumio[1100]: info: Executing endpoint getStreamUrlqobuz Mar 19 18:19:04 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 19 18:19:05 volumio volumio[1100]: info: getStreamUrl took 409 milliseconds Mar 19 18:19:05 volumio volumio[1100]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1477736&eid=51618637&fmt=6&profile=raw&app_id=539451548&cid=1287337&etsp=1742408345&hmac=-RzzLodtrF0yPlfid119b8FDIWY Mar 19 18:19:05 volumio volumio[1100]: STREAMING PROXY: Response: 200, length: 19669611 Mar 19 18:19:05 volumio volumio[1100]: STREAMING PROXY: Client dropped request, destroying Mar 19 18:19:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/51618637" Mar 19 18:19:05 volumio volumio[1100]: info: Mar 19 18:19:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update Mar 19 18:19:05 volumio volumio[1100]: info: Ignoring MPD Status Update Mar 19 18:19:05 volumio volumio[1100]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/51618637" took 0 milliseconds Mar 19 18:19:05 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 18:19:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand play Mar 19 18:19:05 volumio volumio[1100]: info: ------------------------------ 1ms Mar 19 18:19:05 volumio volumio[1100]: info: sendMpdCommand play took 1 milliseconds Mar 19 18:19:05 volumio volumio[1100]: STREAMING PROXY: Handling url /?data=qobuz://song/51618637 Mar 19 18:19:05 volumio volumio[1100]: info: Executing endpoint getStreamUrlqobuz Mar 19 18:19:05 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 19 18:19:06 volumio volumio[1100]: info: getStreamUrl took 417 milliseconds Mar 19 18:19:06 volumio volumio[1100]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1477736&eid=51618637&fmt=6&profile=raw&app_id=539451548&cid=1287337&etsp=1742408346&hmac=y15pzU7MbqaHai8zQxwmn_FmXZI Mar 19 18:19:06 volumio volumio[1100]: STREAMING PROXY: Response: 200, length: 19669611 Mar 19 18:19:06 volumio volumio[1100]: info: Mar 19 18:19:06 volumio volumio[1100]: ---------------------------- MPD announces state update: player Mar 19 18:19:06 volumio volumio[1100]: info: ControllerMpd::getState Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:19:06 volumio volumio[1100]: info: Mar 19 18:19:06 volumio volumio[1100]: ---------------------------- MPD announces state update: player Mar 19 18:19:06 volumio volumio[1100]: info: sendMpdCommand status took 3 milliseconds Mar 19 18:19:06 volumio volumio[1100]: info: ControllerMpd::getState Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::parseState Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:19:06 volumio volumio[1100]: info: sendMpdCommand status took 2 milliseconds Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::parseState Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:19:06 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 19 18:19:06 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:19:06 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:19:06 volumio volumio[1100]: info: ControllerMpd::pushState Mar 19 18:19:06 volumio volumio[1100]: info: CoreCommandRouter::servicePushState Mar 19 18:19:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 Mar 19 18:19:06 volumio volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":207,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"51618637","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/51618637","trackType":"qobuz"} Mar 19 18:19:06 volumio volumio[1100]: verbose: CURRENT POSITION 0 Mar 19 18:19:06 volumio volumio[1100]: info: CoreStateMachine::syncState stateService play Mar 19 18:19:06 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop Mar 19 18:19:06 volumio volumio[1100]: info: ControllerMpd::pushState Mar 19 18:19:06 volumio volumio[1100]: info: CoreCommandRouter::servicePushState Mar 19 18:19:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 Mar 19 18:19:06 volumio volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":207,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"51618637","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/51618637","trackType":"qobuz"} Mar 19 18:19:06 volumio volumio[1100]: verbose: CURRENT POSITION 0 Mar 19 18:19:06 volumio volumio[1100]: info: CoreStateMachine::syncState stateService play Mar 19 18:19:06 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:19:06 volumio volumio[1100]: info: Received an update from plugin. extracting info from payload Mar 19 18:19:06 volumio volumio[1100]: info: CoreStateMachine::pushState Mar 19 18:19:06 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:19:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState Mar 19 18:19:06 volumio volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Mar 19 18:19:06 volumio volumio[1100]: info: MRS: Pushing multiroomSync output Mar 19 18:19:06 volumio volumio[1100]: info: CoreStateMachine::pushState Mar 19 18:19:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState Mar 19 18:19:06 volumio volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Mar 19 18:19:06 volumio volumio[1100]: info: MRS: Pushing multiroomSync output Mar 19 18:19:06 volumio volumio[1100]: info: ------------------------------ 15ms Mar 19 18:19:06 volumio volumio[1100]: info: ------------------------------ 19ms Mar 19 18:19:07 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:07 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:07 volumio volumio[1100]: STREAMING PROXY: Client dropped request, destroying Mar 19 18:19:07 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:07 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:08 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:08 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:08 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:08 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:15 volumio volumio[1100]: error: Failed request for metavolumio API Mar 19 18:19:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 19 18:19:15 volumio volumio[1100]: error: Failed request for metavolumio API Mar 19 18:19:15 volumio volumio[1100]: info: handleBrowseUri took 599 milliseconds Mar 19 18:19:15 volumio volumio[1100]: info: Preload queue cleared Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716157 Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716158 Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716159 Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716160 Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716161 Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716162 Mar 19 18:19:15 volumio volumio[1100]: info: Preloading song: qobuz://song/716163 Mar 19 18:19:15 volumio volumio[1100]: info: Exploding uri qobuz://song/716157 in service qobuz Mar 19 18:19:15 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: Exploding uri qobuz://song/716158 in service qobuz Mar 19 18:19:16 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: Exploding uri qobuz://song/716159 in service qobuz Mar 19 18:19:16 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: Exploding uri qobuz://song/716160 in service qobuz Mar 19 18:19:16 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:16 volumio volumio[1100]: info: Exploding uri qobuz://song/716161 in service qobuz Mar 19 18:19:16 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: Exploding uri qobuz://song/716162 in service qobuz Mar 19 18:19:16 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: Exploding uri qobuz://song/716163 in service qobuz Mar 19 18:19:16 volumio volumio[1100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 466 milliseconds Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 486 milliseconds Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 515 milliseconds Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 501 milliseconds Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 552 milliseconds Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 504 milliseconds Mar 19 18:19:16 volumio volumio[1100]: info: explodeUri took 922 milliseconds Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 19 18:19:18 volumio volumio[1100]: info: Discovery: Getting this device information Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::volumioGetState Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 19 18:19:18 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:18 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:19 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:19 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:20 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:20 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:20 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:20 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:20 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:20 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:20 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:20 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:21 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:21 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:21 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:21 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:22 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:22 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:22 volumio volumio[1100]: info: Executing endpoint metavolumio Mar 19 18:19:22 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 19 18:19:23 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:23 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:23 volumio volumio[1100]: info: Executing endpoint getSimilarAlbums Mar 19 18:19:23 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 19 18:19:23 volumio volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 18:19:23 volumio volumio[1100]: Error: read ECONNRESET Mar 19 18:19:23 volumio volumio[1100]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 19 18:19:23 volumio volumio[1100]: errno: -104, Mar 19 18:19:23 volumio volumio[1100]: code: 'ECONNRESET', Mar 19 18:19:23 volumio volumio[1100]: syscall: 'read' Mar 19 18:19:23 volumio volumio[1100]: } Mar 19 18:19:23 volumio volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 18:19:24 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-19 18:18 Mar 19 18:19:24 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:02:39 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="e62831d2665580a7904f9309b43b2eea"