-- Logs begin at Mon 2026-03-23 23:53:05 MSK, end at Tue 2026-03-24 20:11:01 MSK. -- Mar 24 20:10:01 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 24 20:10:02 smsln100 volumio[802]: info: handleBrowseUri took 800 milliseconds Mar 24 20:10:02 smsln100 volumio[802]: info: Preload queue cleared Mar 24 20:10:04 smsln100 volumio[802]: info: CorePlayQueue::getTrack 2 Mar 24 20:10:04 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:04 smsln100 volumio[802]: info: Prefetching next song Mar 24 20:10:04 smsln100 volumio[802]: info: [1774372204231] ControllerQobuz::prefetch Mar 24 20:10:04 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/8606360" Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Handling url /?data=qobuz://song/8606360 Mar 24 20:10:04 smsln100 volumio[802]: info: Executing endpoint getStreamUrlqobuz Mar 24 20:10:04 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 24 20:10:04 smsln100 volumio[802]: info: getStreamUrl took 367 milliseconds Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9839955&eid=8606360&fmt=6&profile=raw&app_id=539451548&cid=3932624&etsp=1774375804&hmac=fkcbdLCFry3nRSBMBoXzBkBOvjs Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Requesting upstream with range: none Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Upstream status code: 200 Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Client closed connection Mar 24 20:10:04 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/8606360" Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Upstream closed. Received 32768 bytes Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Incomplete transfer. Expected 27795085, got 32768 Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Retrying with range: bytes=32768- (attempt 1) Mar 24 20:10:04 smsln100 volumio[802]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/8606360" took 4 milliseconds Mar 24 20:10:04 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 20:10:04 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 24 20:10:04 smsln100 volumio[802]: STREAMING PROXY: Handling url /?data=qobuz://song/8606360 Mar 24 20:10:04 smsln100 volumio[802]: info: Mar 24 20:10:04 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:04 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:04 smsln100 volumio[802]: info: sendMpdCommand consume 1 took 8 milliseconds Mar 24 20:10:04 smsln100 volumio[802]: info: Mar 24 20:10:04 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:04 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:04 smsln100 volumio[802]: info: Mar 24 20:10:04 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:04 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:04 smsln100 volumio[802]: info: ------------------------------ 19ms Mar 24 20:10:04 smsln100 volumio[802]: info: ------------------------------ 12ms Mar 24 20:10:04 smsln100 volumio[802]: info: ------------------------------ 9ms Mar 24 20:10:04 smsln100 volumio[802]: info: Executing endpoint getStreamUrlqobuz Mar 24 20:10:04 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 24 20:10:05 smsln100 volumio[802]: info: getStreamUrl took 367 milliseconds Mar 24 20:10:05 smsln100 volumio[802]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9839955&eid=8606360&fmt=6&profile=raw&app_id=539451548&cid=3932624&etsp=1774375805&hmac=aNbfY_bmDMM3Fxg1x6ujarcmiGE Mar 24 20:10:05 smsln100 volumio[802]: STREAMING PROXY: Requesting upstream with range: none Mar 24 20:10:05 smsln100 volumio[802]: STREAMING PROXY: Upstream status code: 200 Mar 24 20:10:05 smsln100 volumio[802]: STREAMING PROXY: Resuming with range: bytes=32768- Mar 24 20:10:06 smsln100 volumio[802]: STREAMING PROXY: Resume upstream status code: 206 Mar 24 20:10:06 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:10:06 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:10:06 smsln100 volumio[802]: info: Discovery: Getting this device information Mar 24 20:10:06 smsln100 volumio[802]: info: CoreCommandRouter::volumioGetState Mar 24 20:10:06 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:10:07 smsln100 systemd[1]: Starting Cleanup of Temporary Directories... Mar 24 20:10:07 smsln100 volumio[802]: verbose: New Socket.io Connection to 192.168.1.67:3000 from 192.168.1.71 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 24 20:10:07 smsln100 volumio[802]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 24 20:10:07 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:10:07 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:10:07 smsln100 systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Mar 24 20:10:07 smsln100 systemd[1]: Started Cleanup of Temporary Directories. Mar 24 20:10:08 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 24 20:10:08 smsln100 volumio[802]: info: CoreStateMachine::startPlaybackTimer Mar 24 20:10:08 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:08 smsln100 volumio[802]: STREAMING PROXY: Upstream closed. Received 27795085 bytes Mar 24 20:10:08 smsln100 volumio[802]: STREAMING PROXY: Client closed connection Mar 24 20:10:09 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:09 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:09 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:09 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:09 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:09 smsln100 volumio[802]: info: handleBrowseUri took 1127 milliseconds Mar 24 20:10:09 smsln100 volumio[802]: info: Preload queue cleared Mar 24 20:10:09 smsln100 volumio[802]: STREAMING PROXY: Resume ended. Received 27762317 bytes Mar 24 20:10:09 smsln100 volumio[802]: info: Executing endpoint getSimilarAlbums Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 24 20:10:09 smsln100 volumio[802]: info: Executing endpoint getSimilarAlbums Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 24 20:10:09 smsln100 volumio[802]: info: Executing endpoint getSimilarAlbums Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 24 20:10:09 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:09 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:09 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:10 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:10 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:10 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:10 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:10 smsln100 go-librespot[1385]: time="2026-03-24T20:10:10+03:00" level=trace msg="sent dealer ping" Mar 24 20:10:10 smsln100 volumio[802]: info: Mar 24 20:10:10 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:10 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:10 smsln100 volumio[802]: info: Mar 24 20:10:10 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:10 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:10 smsln100 volumio[802]: info: Mar 24 20:10:10 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:10 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:10 smsln100 volumio[802]: info: Mar 24 20:10:10 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:10 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:10 smsln100 volumio[802]: info: Mar 24 20:10:10 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:10 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:10 smsln100 volumio[802]: info: Mar 24 20:10:10 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:10 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:10 smsln100 go-librespot[1385]: time="2026-03-24T20:10:10+03:00" level=trace msg="received dealer pong" Mar 24 20:10:10 smsln100 volumio[802]: info: ------------------------------ 24ms Mar 24 20:10:10 smsln100 volumio[802]: info: sendMpdCommand status took 21 milliseconds Mar 24 20:10:10 smsln100 volumio[802]: info: ------------------------------ 20ms Mar 24 20:10:10 smsln100 volumio[802]: info: sendMpdCommand status took 16 milliseconds Mar 24 20:10:10 smsln100 volumio[802]: info: ------------------------------ 15ms Mar 24 20:10:10 smsln100 volumio[802]: info: sendMpdCommand status took 11 milliseconds Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:10 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 20:10:10 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 24 20:10:10 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:10 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:10 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"651 Kbps","isStreaming":false,"title":"8606360","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8606360","trackType":"qobuz"} Mar 24 20:10:10 smsln100 volumio[802]: verbose: CURRENT POSITION 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:10 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:10 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"651 Kbps","isStreaming":false,"title":"8606360","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8606360","trackType":"qobuz"} Mar 24 20:10:10 smsln100 volumio[802]: verbose: CURRENT POSITION 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:10 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:10 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"651 Kbps","isStreaming":false,"title":"8606360","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8606360","trackType":"qobuz"} Mar 24 20:10:10 smsln100 volumio[802]: verbose: CURRENT POSITION 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:10 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:10 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:10 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:10 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:10 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:10 smsln100 volumio[802]: info: ------------------------------ 237ms Mar 24 20:10:10 smsln100 volumio[802]: info: ------------------------------ 233ms Mar 24 20:10:10 smsln100 volumio[802]: info: ------------------------------ 229ms Mar 24 20:10:10 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:10 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:10 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:10 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:10 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:10 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:15 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 24 20:10:16 smsln100 volumio[802]: info: handleBrowseUri took 474 milliseconds Mar 24 20:10:16 smsln100 volumio[802]: info: Preload queue cleared Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022120 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022121 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022122 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022123 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022124 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022125 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022126 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022127 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022128 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022129 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022130 Mar 24 20:10:16 smsln100 volumio[802]: info: Preloading song: qobuz://song/333022131 Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022120 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Executing endpoint getSimilarAlbums Mar 24 20:10:16 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022121 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Executing endpoint getSimilarAlbums Mar 24 20:10:16 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 24 20:10:16 smsln100 volumio[802]: info: Executing endpoint getSimilarAlbums Mar 24 20:10:16 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022122 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022123 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:16 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:16 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:16 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:16 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:16 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022124 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022125 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022126 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022127 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: explodeUri took 433 milliseconds Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022128 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022129 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022130 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: Exploding uri qobuz://song/333022131 in service qobuz Mar 24 20:10:16 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:16 smsln100 volumio[802]: info: explodeUri took 649 milliseconds Mar 24 20:10:16 smsln100 volumio[802]: info: explodeUri took 583 milliseconds Mar 24 20:10:16 smsln100 volumio[802]: info: explodeUri took 626 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 642 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 706 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 653 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:17 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 604 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 521 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 657 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 681 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: explodeUri took 928 milliseconds Mar 24 20:10:17 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:17 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:17 smsln100 volumio[802]: info: Executing endpoint metavolumio Mar 24 20:10:17 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 24 20:10:19 smsln100 volumio[802]: info: Preload queue cleared Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::ClearQueue Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::stPlaybackTimer Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::updateTrackBlock Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrackBlock Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::serviceStop Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::serviceStop Mar 24 20:10:19 smsln100 volumio[802]: info: [1774372219241] ControllerQobuz::stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::stop Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::clearPlayQueue Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::saveQueue Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::addQueueItems Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::addQueueItems Mar 24 20:10:19 smsln100 volumio[802]: info: Preload queue cleared Mar 24 20:10:19 smsln100 volumio[802]: info: Adding Item to queue: qobuz://album/ewmagw0bwdf0b Mar 24 20:10:19 smsln100 volumio[802]: info: Exploding uri qobuz://album/ewmagw0bwdf0b in service qobuz Mar 24 20:10:19 smsln100 volumio[802]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Mar 24 20:10:19 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand stop took 110 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand status took 5 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:19 smsln100 volumio[802]: info: Mar 24 20:10:19 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:19 smsln100 volumio[802]: info: Mar 24 20:10:19 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand status took 10 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand status took 5 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:19 smsln100 volumio[802]: 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":"8606360","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8606360","trackType":"qobuz"} Mar 24 20:10:19 smsln100 volumio[802]: verbose: CURRENT POSITION 3 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: No code Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: ------------------------------ 133ms Mar 24 20:10:19 smsln100 volumio[802]: error: Received invalid volume value from serial: Mar 24 20:10:19 smsln100 volumio[802]: error: Serial API: Failed to decode command: 100, message: undefined Mar 24 20:10:19 smsln100 volumio[802]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 24 20:10:19 smsln100 volumio[802]: error: Serial API: Failed to decode command: 100, message: undefined Mar 24 20:10:19 smsln100 volumio[802]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 105 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 105 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:19 smsln100 volumio[802]: 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":"8606360","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8606360","trackType":"qobuz"} Mar 24 20:10:19 smsln100 volumio[802]: verbose: CURRENT POSITION 3 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: No code Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 3 Mar 24 20:10:19 smsln100 volumio[802]: 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":"8606360","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/8606360","trackType":"qobuz"} Mar 24 20:10:19 smsln100 volumio[802]: verbose: CURRENT POSITION 3 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: No code Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:19 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:19 smsln100 volumio[802]: info: ------------------------------ 298ms Mar 24 20:10:19 smsln100 volumio[802]: info: ------------------------------ 294ms Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:19 smsln100 volumio[802]: error: Received invalid volume value from serial: Mar 24 20:10:19 smsln100 volumio[802]: error: Serial API: Failed to decode command: 100, message: undefined Mar 24 20:10:19 smsln100 volumio[802]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 24 20:10:19 smsln100 volumio[802]: error: Serial API: Failed to decode command: 100, message: undefined Mar 24 20:10:19 smsln100 volumio[802]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 24 20:10:19 smsln100 volumio[802]: info: explodeUri took 686 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::saveQueue Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::updateTrackBlock Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrackBlock Mar 24 20:10:19 smsln100 volumio[802]: info: CoreCommandRouter::volumioPlay Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::play index 0 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::stop Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::play index undefined Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:19 smsln100 volumio[802]: info: CoreStateMachine::startPlaybackTimer Mar 24 20:10:19 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:19 smsln100 volumio[802]: info: [1774372219967] ControllerQobuz::clearAddPlayTrack Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:10:19 smsln100 volumio[802]: info: sendMpdCommand stop took 16 milliseconds Mar 24 20:10:19 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 20:10:19 smsln100 volumio[802]: info: Mar 24 20:10:19 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:20 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:20 smsln100 volumio[802]: info: sendMpdCommand clear took 13 milliseconds Mar 24 20:10:20 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/333022120" Mar 24 20:10:20 smsln100 volumio[802]: info: Mar 24 20:10:20 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:20 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:20 smsln100 volumio[802]: info: Mar 24 20:10:20 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Handling url /?data=qobuz://song/333022120 Mar 24 20:10:20 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:20 smsln100 volumio[802]: error: updateQueue error: null Mar 24 20:10:20 smsln100 volumio[802]: info: ------------------------------ 18ms Mar 24 20:10:20 smsln100 volumio[802]: info: Executing endpoint getStreamUrlqobuz Mar 24 20:10:20 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 24 20:10:20 smsln100 volumio[802]: info: getStreamUrl took 328 milliseconds Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9839955&eid=333022120&fmt=7&profile=raw&app_id=539451548&cid=3932624&etsp=1774375820&hmac=BeoEtLskDxYa6gNf6hwOAm7EZiU Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Requesting upstream with range: none Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Upstream status code: 200 Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Client closed connection Mar 24 20:10:20 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/333022120" Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Upstream closed. Received 16384 bytes Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Incomplete transfer. Expected 42025000, got 16384 Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Retrying with range: bytes=16384- (attempt 1) Mar 24 20:10:20 smsln100 volumio[802]: error: updateQueue error: null Mar 24 20:10:20 smsln100 volumio[802]: error: updateQueue error: null Mar 24 20:10:20 smsln100 volumio[802]: info: Mar 24 20:10:20 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:20 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:20 smsln100 volumio[802]: info: ------------------------------ 553ms Mar 24 20:10:20 smsln100 volumio[802]: info: ------------------------------ 550ms Mar 24 20:10:20 smsln100 volumio[802]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/333022120" took 10 milliseconds Mar 24 20:10:20 smsln100 volumio[802]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 20:10:20 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand play Mar 24 20:10:20 smsln100 volumio[802]: info: Mar 24 20:10:20 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:20 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Handling url /?data=qobuz://song/333022120 Mar 24 20:10:20 smsln100 volumio[802]: info: Mar 24 20:10:20 smsln100 volumio[802]: ---------------------------- MPD announces system playlist update Mar 24 20:10:20 smsln100 volumio[802]: info: Ignoring MPD Status Update Mar 24 20:10:20 smsln100 volumio[802]: info: ------------------------------ 19ms Mar 24 20:10:20 smsln100 volumio[802]: info: sendMpdCommand play took 13 milliseconds Mar 24 20:10:20 smsln100 volumio[802]: info: ------------------------------ 12ms Mar 24 20:10:20 smsln100 volumio[802]: info: ------------------------------ 9ms Mar 24 20:10:20 smsln100 volumio[802]: info: Executing endpoint getStreamUrlqobuz Mar 24 20:10:20 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 24 20:10:20 smsln100 volumio[802]: info: getStreamUrl took 294 milliseconds Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=9839955&eid=333022120&fmt=7&profile=raw&app_id=539451548&cid=3932624&etsp=1774375820&hmac=BeoEtLskDxYa6gNf6hwOAm7EZiU Mar 24 20:10:20 smsln100 volumio[802]: STREAMING PROXY: Requesting upstream with range: none Mar 24 20:10:21 smsln100 volumio[802]: STREAMING PROXY: Upstream status code: 200 Mar 24 20:10:21 smsln100 volumio[802]: info: Mar 24 20:10:21 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:21 smsln100 volumio[802]: info: Mar 24 20:10:21 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:21 smsln100 volumio[802]: info: Mar 24 20:10:21 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:21 smsln100 volumio[802]: info: Mar 24 20:10:21 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand status took 17 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand status took 12 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand status took 8 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:21 smsln100 volumio[802]: info: Mar 24 20:10:21 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:21 smsln100 volumio[802]: info: Mar 24 20:10:21 smsln100 volumio[802]: ---------------------------- MPD announces state update: player Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::getState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand status took 27 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand status took 16 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:21 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:21 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333022120","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/333022120","trackType":"qobuz"} Mar 24 20:10:21 smsln100 volumio[802]: verbose: CURRENT POSITION 0 Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:21 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:21 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333022120","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/333022120","trackType":"qobuz"} Mar 24 20:10:21 smsln100 volumio[802]: verbose: CURRENT POSITION 0 Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:21 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:21 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:21 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333022120","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/333022120","trackType":"qobuz"} Mar 24 20:10:21 smsln100 volumio[802]: verbose: CURRENT POSITION 0 Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:21 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: ------------------------------ 159ms Mar 24 20:10:21 smsln100 volumio[802]: info: ------------------------------ 249ms Mar 24 20:10:21 smsln100 volumio[802]: info: ------------------------------ 248ms Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand status took 224 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 212 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 208 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseState Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:21 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:21 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333022120","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/333022120","trackType":"qobuz"} Mar 24 20:10:21 smsln100 volumio[802]: verbose: CURRENT POSITION 0 Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:21 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:21 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:21 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333022120","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/333022120","trackType":"qobuz"} Mar 24 20:10:21 smsln100 volumio[802]: verbose: CURRENT POSITION 0 Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:21 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: STREAMING PROXY: Resuming with range: bytes=16384- Mar 24 20:10:21 smsln100 volumio[802]: info: ------------------------------ 508ms Mar 24 20:10:21 smsln100 volumio[802]: info: ------------------------------ 496ms Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:21 smsln100 volumio[802]: info: sendMpdCommand playlistinfo took 517 milliseconds Mar 24 20:10:21 smsln100 volumio[802]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:10:21 smsln100 volumio[802]: info: ControllerMpd::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::servicePushState Mar 24 20:10:21 smsln100 volumio[802]: info: CorePlayQueue::getTrack 0 Mar 24 20:10:21 smsln100 volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333022120","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/333022120","trackType":"qobuz"} Mar 24 20:10:21 smsln100 volumio[802]: verbose: CURRENT POSITION 0 Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState stateService play Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:10:21 smsln100 volumio[802]: info: Received an update from plugin. extracting info from payload Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:21 smsln100 volumio[802]: info: CoreStateMachine::pushState Mar 24 20:10:21 smsln100 volumio[802]: info: CoreCommandRouter::volumioPushState Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output update for this device Mar 24 20:10:21 smsln100 volumio[802]: info: MRS: Pushing multiroomSync output Mar 24 20:10:22 smsln100 volumio[802]: info: ------------------------------ 878ms Mar 24 20:10:22 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:22 smsln100 volumio[802]: info: Updating RAAT Signal Path Mar 24 20:10:22 smsln100 volumio[802]: STREAMING PROXY: Resume upstream status code: 206 Mar 24 20:10:24 smsln100 volumio[802]: STREAMING PROXY: Upstream closed. Received 42025000 bytes Mar 24 20:10:24 smsln100 volumio[802]: STREAMING PROXY: Client closed connection Mar 24 20:10:27 smsln100 volumio[802]: STREAMING PROXY: Resume ended. Received 42008616 bytes Mar 24 20:10:40 smsln100 go-librespot[1385]: time="2026-03-24T20:10:40+03:00" level=trace msg="sent dealer ping" Mar 24 20:10:40 smsln100 go-librespot[1385]: time="2026-03-24T20:10:40+03:00" level=trace msg="received dealer pong" Mar 24 20:10:40 smsln100 nmbd[739]: [2026/03/24 20:10:40.827846, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 24 20:10:40 smsln100 nmbd[739]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.67 for name WORKGROUP<1d>. Mar 24 20:10:40 smsln100 nmbd[739]: This response was from IP 192.168.1.91, reporting an IP address of 192.168.1.91. Mar 24 20:10:56 smsln100 volumio[802]: Searching all installed plugins Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: , search Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 24 20:10:56 smsln100 volumio[802]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 24 20:10:56 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 24 20:10:57 smsln100 volumio[802]: info: Произошла ошибка при запросе SHOUTCAST Mar 24 20:10:57 smsln100 volumio[802]: info: searchTIDALUri took 670 milliseconds Mar 24 20:10:57 smsln100 volumio[802]: info: search took 686 milliseconds Mar 24 20:10:57 smsln100 volumio[802]: info: searchQOBUZUri took 1205 milliseconds Mar 24 20:10:57 smsln100 volumio[802]: info: All search sources collected, pushing search results Mar 24 20:10:59 smsln100 volumio[802]: Searching all installed plugins Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: , search Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 24 20:10:59 smsln100 volumio[802]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 24 20:10:59 smsln100 volumio[802]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 24 20:10:59 smsln100 volumio[802]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Mar 24 20:10:59 smsln100 volumio[802]: info: searchTIDALUri took 682 milliseconds Mar 24 20:10:59 smsln100 volumio[802]: info: search took 699 milliseconds Mar 24 20:11:00 smsln100 volumio[802]: info: searchQOBUZUri took 1364 milliseconds Mar 24 20:11:00 smsln100 volumio[802]: info: All search sources collected, pushing search results Mar 24 20:11:00 smsln100 volumio[802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:11:00 smsln100 volumio[802]: TypeError: Cannot read property 'slice' of null Mar 24 20:11:00 smsln100 volumio[802]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 24 20:11:00 smsln100 volumio[802]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 24 20:11:00 smsln100 volumio[802]: at Object.onceWrapper (events.js:520:26) Mar 24 20:11:00 smsln100 volumio[802]: at ClientRequest.emit (events.js:400:28) Mar 24 20:11:00 smsln100 volumio[802]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Mar 24 20:11:00 smsln100 volumio[802]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Mar 24 20:11:00 smsln100 volumio[802]: at TLSSocket.socketOnData (_http_client.js:515:22) Mar 24 20:11:00 smsln100 volumio[802]: at TLSSocket.emit (events.js:400:28) Mar 24 20:11:00 smsln100 volumio[802]: at addChunk (internal/streams/readable.js:293:12) Mar 24 20:11:00 smsln100 volumio[802]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 24 20:11:00 smsln100 volumio[802]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Mar 24 20:11:00 smsln100 volumio[802]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 24 20:11:00 smsln100 volumio[802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:11:01 smsln100 sudo[5038]: volumio : unable to resolve host smsln100 Mar 24 20:11:01 smsln100 sudo[5038]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 24 20:11:01 smsln100 sudo[5038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-24 20:10 Mar 24 20:11:01 smsln100 sudo[5038]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 12:45:15 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="03b42fda2a69e26429b08d8b6f7dc241"