-- Logs begin at Sun 2026-01-25 19:12:49 EST, end at Wed 2026-01-28 12:28:48 EST. -- Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 12:27:07 volumiopi4 volumio[1201]: info: Discovery: Getting this device information Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 12:27:07 volumiopi4 volumio[1201]: verbose: New Socket.io Connection to 192.168.1.159:3000 from 192.168.1.71 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 12:27:07 volumiopi4 volumio[1201]: info: Discovery: Getting this device information Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 12:27:07 volumiopi4 volumio[1201]: verbose: New Socket.io Connection to 192.168.1.161:3000 from 192.168.1.71 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 12:27:07 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioNext Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::next Jan 28 12:27:09 volumiopi4 volumio[1201]: info: [1769621229835] ControllerQobuz::next Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::next Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::stop Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:09 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::serviceStop Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::serviceStop Jan 28 12:27:09 volumiopi4 volumio[1201]: info: [1769621229842] ControllerQobuz::stop Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:09 volumiopi4 volumio[1201]: info: ControllerMpd::stop Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:27:09 volumiopi4 volumio[1201]: info: Jan 28 12:27:09 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:09 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 10 milliseconds Jan 28 12:27:09 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::play index undefined Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::startPlaybackTimer Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: [1769621229853] ControllerQobuz::clearAddPlayTrack Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:27:09 volumiopi4 volumio[1201]: info: sendMpdCommand status took 3 milliseconds Jan 28 12:27:09 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 1 milliseconds Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 12:27:09 volumiopi4 volumio[1201]: info: Jan 28 12:27:09 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:27:09 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:27:09 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 12:27:09 volumiopi4 volumio[1201]: info: sendMpdCommand clear took 1 milliseconds Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:09 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/138932075" Jan 28 12:27:09 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:09 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 28 12:27:09 volumiopi4 volumio[1201]: STREAMING PROXY: Handling url /?data=qobuz://song/138932075 Jan 28 12:27:09 volumiopi4 volumio[1201]: info: ------------------------------ 15ms Jan 28 12:27:09 volumiopi4 volumio[1201]: error: updateQueue error: null Jan 28 12:27:09 volumiopi4 volumio[1201]: info: ------------------------------ 12ms Jan 28 12:27:09 volumiopi4 volumio[1201]: info: Executing endpoint getStreamUrlqobuz Jan 28 12:27:09 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 12:27:10 volumiopi4 volumio[1201]: info: getStreamUrl took 283 milliseconds Jan 28 12:27:10 volumiopi4 volumio[1201]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2675691&eid=138932075&fmt=6&profile=raw&app_id=539451548&cid=1858634&etsp=1769624830&hmac=t6yAuq-UO_bTUuVsWcExzALvZzk Jan 28 12:27:11 volumiopi4 volumio[1201]: STREAMING PROXY: Response: 200, length: 42525559 Jan 28 12:27:11 volumiopi4 volumio[1201]: STREAMING PROXY: Client dropped request, destroying Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138932075" Jan 28 12:27:11 volumiopi4 volumio[1201]: info: Jan 28 12:27:11 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:27:11 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:27:11 volumiopi4 volumio[1201]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138932075" took 1 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ------------------------------ 1ms Jan 28 12:27:11 volumiopi4 volumio[1201]: info: sendMpdCommand play took 1 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: STREAMING PROXY: Handling url /?data=qobuz://song/138932075 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: Executing endpoint getStreamUrlqobuz Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 12:27:11 volumiopi4 volumio[1201]: info: getStreamUrl took 233 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2675691&eid=138932075&fmt=6&profile=raw&app_id=539451548&cid=1858634&etsp=1769624831&hmac=TcbSVKbpaGk4VuUjhYpejs3zybc Jan 28 12:27:11 volumiopi4 volumio[1201]: STREAMING PROXY: Response: 200, length: 42525559 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: Jan 28 12:27:11 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:11 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:11 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:11 volumiopi4 volumio[1201]: info: Jan 28 12:27:11 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:11 volumiopi4 volumio[1201]: info: sendMpdCommand status took 68 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:11 volumiopi4 volumio[1201]: info: sendMpdCommand status took 2 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138932075","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932075","trackType":"qobuz"} Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 1 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ------------------------------ 73ms Jan 28 12:27:11 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:11 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:11 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"138932075","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932075","trackType":"qobuz"} Jan 28 12:27:11 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 1 Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:27:11 volumiopi4 volumio[1201]: info: Received an update from plugin. extracting info from payload Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:11 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:11 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:11 volumiopi4 volumio[1201]: info: ------------------------------ 18ms Jan 28 12:27:13 volumiopi4 volumio[1201]: STREAMING PROXY: Client dropped request, destroying Jan 28 12:27:18 volumiopi4 volumio[1201]: info: VolumeController::SetAlsaVolume47 Jan 28 12:27:18 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:18 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:18 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:18 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:18 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:18 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:28 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:28 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto Jan 28 12:27:28 volumiopi4 volumio[1201]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 28 12:27:28 volumiopi4 volumio[1201]: info: explodeUri took 216 milliseconds Jan 28 12:27:29 volumiopi4 volumio[1201]: info: handleBrowseUri took 1190 milliseconds Jan 28 12:27:29 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:27:29 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:29 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:29 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:29 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:30 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:30 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:30 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:30 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 28 12:27:31 volumiopi4 volumio[1201]: info: handleBrowseUri took 288 milliseconds Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:31 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:32 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:32 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:32 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:32 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::ClearQueue Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::stop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:33 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:33 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::serviceStop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::serviceStop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: [1769621253300] ControllerQobuz::stop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:33 volumiopi4 volumio[1201]: info: ControllerMpd::stop Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::clearPlayQueue Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::saveQueue Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushQueue Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::addQueueItems Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::addQueueItems Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Adding Item to queue: qobuz://album/gh4vjulrtfdgb Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Using cached record of: qobuz://album/gh4vjulrtfdgb Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushQueue Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::saveQueue Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPlay Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::play index 0 Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::stop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::play index undefined Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreStateMachine::startPlaybackTimer Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:33 volumiopi4 volumio[1201]: info: [1769621253311] ControllerQobuz::clearAddPlayTrack Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Jan 28 12:27:33 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:33 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 18 milliseconds Jan 28 12:27:33 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:33 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 7 milliseconds Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Jan 28 12:27:33 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:27:33 volumiopi4 volumio[1201]: info: sendMpdCommand status took 1 milliseconds Jan 28 12:27:33 volumiopi4 volumio[1201]: info: sendMpdCommand clear took 1 milliseconds Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/138932074" Jan 28 12:27:33 volumiopi4 volumio[1201]: error: updateQueue error: null Jan 28 12:27:33 volumiopi4 volumio[1201]: info: ------------------------------ 2ms Jan 28 12:27:33 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 12:27:33 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:33 volumiopi4 volumio[1201]: STREAMING PROXY: Handling url /?data=qobuz://song/138932074 Jan 28 12:27:33 volumiopi4 volumio[1201]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 28 12:27:33 volumiopi4 volumio[1201]: info: ------------------------------ 6ms Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Executing endpoint getStreamUrlqobuz Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 12:27:33 volumiopi4 volumio[1201]: info: getStreamUrl took 282 milliseconds Jan 28 12:27:33 volumiopi4 volumio[1201]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2675691&eid=138932074&fmt=6&profile=raw&app_id=539451548&cid=1858634&etsp=1769624853&hmac=ppIkJxspzdIdfUcTDz_FJHEXM7s Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:33 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:33 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:34 volumiopi4 volumio[1201]: STREAMING PROXY: Response: 200, length: 28399641 Jan 28 12:27:34 volumiopi4 volumio[1201]: STREAMING PROXY: Client dropped request, destroying Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138932074" Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Jan 28 12:27:34 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:27:34 volumiopi4 volumio[1201]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138932074" took 1 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ------------------------------ 2ms Jan 28 12:27:34 volumiopi4 volumio[1201]: info: sendMpdCommand play took 1 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: STREAMING PROXY: Handling url /?data=qobuz://song/138932074 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Executing endpoint getStreamUrlqobuz Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 12:27:34 volumiopi4 volumio[1201]: info: getStreamUrl took 258 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2675691&eid=138932074&fmt=6&profile=raw&app_id=539451548&cid=1858634&etsp=1769624854&hmac=3P_-Kxq3PCTj7wJD9eO9-vCS36M Jan 28 12:27:34 volumiopi4 volumio[1201]: STREAMING PROXY: Response: 200, length: 28399641 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Jan 28 12:27:34 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:34 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:34 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Jan 28 12:27:34 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:34 volumiopi4 volumio[1201]: info: sendMpdCommand status took 26 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:34 volumiopi4 volumio[1201]: info: sendMpdCommand status took 2 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138932074","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932074","trackType":"qobuz"} Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ------------------------------ 31ms Jan 28 12:27:34 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:34 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:34 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"513 Kbps","isStreaming":false,"title":"138932074","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932074","trackType":"qobuz"} Jan 28 12:27:34 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Received an update from plugin. extracting info from payload Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:34 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:34 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:34 volumiopi4 volumio[1201]: info: ------------------------------ 24ms Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:34 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:27:34 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:27:35 volumiopi4 volumio[1201]: STREAMING PROXY: Client dropped request, destroying Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioSeek Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::seek Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:37 volumiopi4 volumio[1201]: info: TRACKBLOCK {"album":"Allzeit Bereit","albumUri":"qobuz://album/gh4vjulrtfdgb","albumart":"https://static.qobuz.com/images/covers/gb/fd/gh4vjulrtfdgb_600.jpg","artist":"Der Tod und die Landsknechte","artistUri":"qobuz://artist/11965410","audioQuality":"","duration":224,"explicit":false,"name":"Allzeit Bereit","service":"qobuz","title":"Allzeit Bereit","tracknumber":1,"type":"track","uri":"qobuz://song/138932074","volumeNumber":1,"tagImage":""} Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::startPlaybackTimer Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:37 volumiopi4 volumio[1201]: info: [1769621257144] ControllerQobuz::seek Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:37 volumiopi4 volumio[1201]: info: ControllerMpd::seek Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:37 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:37 volumiopi4 volumio[1201]: error: null Jan 28 12:27:37 volumiopi4 volumio[1201]: info: Jan 28 12:27:37 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:37 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:37 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:37 volumiopi4 volumio[1201]: info: sendMpdCommand status took 5 milliseconds Jan 28 12:27:37 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:37 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:37 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 28 12:27:37 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:37 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:37 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23212,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1055 Kbps","isStreaming":false,"title":"138932074","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932074","trackType":"qobuz"} Jan 28 12:27:37 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:27:37 volumiopi4 volumio[1201]: info: Received an update from plugin. extracting info from payload Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:37 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:37 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:37 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:37 volumiopi4 volumio[1201]: info: ------------------------------ 20ms Jan 28 12:27:38 volumiopi4 volumio[1201]: info: VolumeController::SetAlsaVolume60 Jan 28 12:27:38 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:38 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:38 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:38 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:38 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:38 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:40 volumiopi4 volumio[1201]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Jan 28 12:27:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jan 28 12:27:40 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:40 volumiopi4 volumio[1201]: info: FusionDsp - Effects disabled Jan 28 12:27:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:27:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:27:40 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:40 volumiopi4 volumio[1201]: info: FusionDsp - Meze empyrean alcantara Jan 28 12:27:40 volumiopi4 volumio[1201]: info: FusionDsp - Empyrean basic.json,Empyrean ii alcantara.json,Empyrean ii duo.json,Empyrean leather.json,Meze Elite alcantara.json,Meze Elite hybrid.json,Meze empyrean alcantara.json Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioNext Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::next Jan 28 12:27:42 volumiopi4 volumio[1201]: info: [1769621262455] ControllerQobuz::next Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::next Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::stop Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:42 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::serviceStop Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::serviceStop Jan 28 12:27:42 volumiopi4 volumio[1201]: info: [1769621262461] ControllerQobuz::stop Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:42 volumiopi4 volumio[1201]: info: ControllerMpd::stop Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:27:42 volumiopi4 volumio[1201]: info: Jan 28 12:27:42 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:42 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 15 milliseconds Jan 28 12:27:42 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::play index undefined Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::startPlaybackTimer Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: [1769621262477] ControllerQobuz::clearAddPlayTrack Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:27:42 volumiopi4 volumio[1201]: info: sendMpdCommand status took 3 milliseconds Jan 28 12:27:42 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 1 milliseconds Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 12:27:42 volumiopi4 volumio[1201]: info: Jan 28 12:27:42 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:27:42 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:27:42 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 12:27:42 volumiopi4 volumio[1201]: info: sendMpdCommand clear took 2 milliseconds Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:42 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/138932075" Jan 28 12:27:42 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:42 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 28 12:27:42 volumiopi4 volumio[1201]: STREAMING PROXY: Handling url /?data=qobuz://song/138932075 Jan 28 12:27:42 volumiopi4 volumio[1201]: info: ------------------------------ 14ms Jan 28 12:27:42 volumiopi4 volumio[1201]: error: updateQueue error: null Jan 28 12:27:42 volumiopi4 volumio[1201]: info: ------------------------------ 11ms Jan 28 12:27:42 volumiopi4 volumio[1201]: info: Executing endpoint getStreamUrlqobuz Jan 28 12:27:42 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 12:27:42 volumiopi4 volumio[1201]: info: getStreamUrl took 233 milliseconds Jan 28 12:27:42 volumiopi4 volumio[1201]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2675691&eid=138932075&fmt=6&profile=raw&app_id=539451548&cid=1858634&etsp=1769624862&hmac=sbPgGfekWTnwkOwc29eSeeosjFo Jan 28 12:27:43 volumiopi4 volumio[1201]: STREAMING PROXY: Response: 200, length: 42525559 Jan 28 12:27:43 volumiopi4 volumio[1201]: STREAMING PROXY: Client dropped request, destroying Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138932075" Jan 28 12:27:43 volumiopi4 volumio[1201]: info: Jan 28 12:27:43 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:27:43 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:27:43 volumiopi4 volumio[1201]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/138932075" took 1 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ------------------------------ 2ms Jan 28 12:27:43 volumiopi4 volumio[1201]: info: sendMpdCommand play took 1 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: STREAMING PROXY: Handling url /?data=qobuz://song/138932075 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: Executing endpoint getStreamUrlqobuz Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jan 28 12:27:43 volumiopi4 volumio[1201]: info: getStreamUrl took 245 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2675691&eid=138932075&fmt=6&profile=raw&app_id=539451548&cid=1858634&etsp=1769624863&hmac=uQxJkNU63FD5j5pLQ5HMHQMhdJs Jan 28 12:27:43 volumiopi4 volumio[1201]: STREAMING PROXY: Response: 200, length: 42525559 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: Jan 28 12:27:43 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - Effects disabled Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:43 volumiopi4 volumio[1201]: info: FusionDsp - Effects disabled Jan 28 12:27:43 volumiopi4 volumio[1201]: info: Jan 28 12:27:43 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:27:43 volumiopi4 volumio[1201]: info: sendMpdCommand status took 13 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:43 volumiopi4 volumio[1201]: info: sendMpdCommand status took 1 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"138932075","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932075","trackType":"qobuz"} Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 1 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ------------------------------ 19ms Jan 28 12:27:43 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:43 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:43 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"138932075","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932075","trackType":"qobuz"} Jan 28 12:27:43 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 1 Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:27:43 volumiopi4 volumio[1201]: info: Received an update from plugin. extracting info from payload Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:43 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:27:43 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:27:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:27:43 volumiopi4 volumio[1201]: info: ------------------------------ 20ms Jan 28 12:27:45 volumiopi4 volumio[1201]: STREAMING PROXY: Client dropped request, destroying Jan 28 12:27:50 volumiopi4 volumio[1201]: info: CALLMETHOD: audio_interface fusiondsp enableeffect undefined Jan 28 12:27:50 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Jan 28 12:27:50 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:50 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:50 volumiopi4 volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:27:50 volumiopi4 volumio[1201]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:27:50 volumiopi4 volumio[1201]: info: FusionDsp - Meze empyrean alcantara Jan 28 12:27:51 volumiopi4 volumio[1201]: info: FusionDsp - Empyrean basic.json,Empyrean ii alcantara.json,Empyrean ii duo.json,Empyrean leather.json,Meze Elite alcantara.json,Meze Elite hybrid.json,Meze empyrean alcantara.json Jan 28 12:27:51 volumiopi4 volumio[1201]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Jan 28 12:27:51 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jan 28 12:27:51 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:51 volumiopi4 volumio[1201]: info: FusionDsp - Effects disabled Jan 28 12:27:51 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:51 volumiopi4 volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:27:51 volumiopi4 volumio[1201]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:27:51 volumiopi4 volumio[1201]: info: FusionDsp - Meze empyrean alcantara Jan 28 12:27:51 volumiopi4 volumio[1201]: info: FusionDsp - Empyrean basic.json,Empyrean ii alcantara.json,Empyrean ii duo.json,Empyrean leather.json,Meze Elite alcantara.json,Meze Elite hybrid.json,Meze empyrean alcantara.json Jan 28 12:27:52 volumiopi4 volumio[1201]: info: CALLMETHOD: audio_interface fusiondsp enableeffect undefined Jan 28 12:27:52 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Jan 28 12:27:52 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:52 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:52 volumiopi4 volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:27:52 volumiopi4 volumio[1201]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:27:52 volumiopi4 volumio[1201]: info: FusionDsp - Meze empyrean alcantara Jan 28 12:27:52 volumiopi4 volumio[1201]: info: FusionDsp - Empyrean basic.json,Empyrean ii alcantara.json,Empyrean ii duo.json,Empyrean leather.json,Meze Elite alcantara.json,Meze Elite hybrid.json,Meze empyrean alcantara.json Jan 28 12:27:53 volumiopi4 volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:27:53 volumiopi4 volumio[1201]: info: FusionDsp - Meze empyrean alcantara Jan 28 12:27:53 volumiopi4 volumio[1201]: info: FusionDsp - Empyrean basic.json,Empyrean ii alcantara.json,Empyrean ii duo.json,Empyrean leather.json,Meze Elite alcantara.json,Meze Elite hybrid.json,Meze empyrean alcantara.json Jan 28 12:27:56 volumiopi4 volumio[1201]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Jan 28 12:27:56 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Jan 28 12:27:56 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:27:56 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:27:56 volumiopi4 volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:27:56 volumiopi4 volumio[1201]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:27:56 volumiopi4 volumio[1201]: info: FusionDsp - Meze empyrean alcantara Jan 28 12:27:56 volumiopi4 volumio[1201]: info: FusionDsp - Empyrean basic.json,Empyrean ii alcantara.json,Empyrean ii duo.json,Empyrean leather.json,Meze Elite alcantara.json,Meze Elite hybrid.json,Meze empyrean alcantara.json Jan 28 12:28:06 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:06 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:06 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:06 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:11 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Jan 28 12:28:11 volumiopi4 volumio[1201]: info: [bandcamp-browse] browseUri: bandcamp Jan 28 12:28:12 volumiopi4 volumio[1201]: error: Failed to execute browseSource: Error: Failed to parse image constants: JSON error. Jan 28 12:28:16 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 12:28:16 volumiopi4 volumio[1201]: info: CURURI: genres:// Jan 28 12:28:16 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:16 volumiopi4 volumio[1201]: 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' Jan 28 12:28:19 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 12:28:19 volumiopi4 volumio[1201]: info: CURURI: genres://%CE%95%CE%BB%CE%BB%CE%AC%CF%82 Jan 28 12:28:19 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:19 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:20 volumiopi4 volumio[1201]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Deviser/The%20Revelation%20of%20Higher%20Mysteries/d45e02db-ecf1-4a74-a42d-6c42452a6034.jpg' Jan 28 12:28:21 volumiopi4 volumio[1201]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mortify/5cc4d237-fa67-4e78-9361-976f94fc932e.jpg' Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CURURI: genres://%CE%95%CE%BB%CE%BB%CE%AC%CF%82/Necromantia Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Ancient Pride/1 - Necromantia - The Shaman.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Ancient Pride/2 - Necromantia - Ancient Pride.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Ancient Pride/3 - Necromantia - For the Light of My Darkness.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Ancient Pride/4 - Necromantia - Each Dawn I Die.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Ancient Pride/5 - Necromantia - The Number of the Beast.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Ancient Pride/6 - Necromantia - Spiritforms of the Psychomancer.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/1 - Necromantia - My World, Your Hell.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/2 - Necromantia - Death Rider.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/3 - Necromantia - The Number of the Beast.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/4 - Necromantia - The Demon’s Whip.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/1 - Necromantia - Those Who Never Sleep.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/2 - Necromantia - Murder, Magic and Tears.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/3 - Necromantia - Ancient Pride.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/4 - Necromantia - For the Light of My Darkness.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/5 - Necromantia - The Black Mirror.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/6 - Necromantia - Pretender to the Throne Part 1- The Usurpers Spawn.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/7 - Necromantia - Scarlet Witching Dreams.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/8 - Necromantia - Les Litanies de Satan.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/9 - Necromantia - Lord of the Abyss.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/2 - Necromantia - The Warlock.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/3 - Necromantia - Last Song for Valdezie.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/4 - Necromantia - Unchaining the Wolf (At War...).flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/5 - Necromantia - Les Litanies de Satan.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/6 - Necromantia - Lord of the Abyss.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/7 - Necromantia - Tribes of the Moon.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/8 - Necromantia - Death Rider.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/1 - Necromantia - Family Of Dog (The Feast Of Ghouls).mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/2 - Necromantia - De Magia Veterum.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/3 - Necromantia - Faceless Gods.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/4 - Necromantia - Evil Prayers.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/5 - Necromantia - Lycanthropia.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/6 - Necromantia - La Mort.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/7 - Necromantia - Lord of the abyss.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/8 - Necromantia - The feast of ghouls.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/9 - Necromantia - Evil pravers.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/De Magia Veterum/10 - Necromantia - Lycanthropia.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Demo '93/01 - Necromantia - The Warlock.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Demo '93/02 - Necromantia - Les Litanies De Satan.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/From The Past We Summon Thee/1 - Necromantia - Faceless Gods.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/From The Past We Summon Thee/2 - Necromantia - Λυκανθρωπια (Lycaon's Metamorphosis).mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/From The Past We Summon Thee/3 - Necromantia - La Mort.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/1 - Necromantia - The Blair Witch Cult.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/2 - Necromantia - Those Who Never Sleep.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/3 - Necromantia - Disciples of Sophia (The Templars).flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/5 - Necromantia - Invictus.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/6 - Necromantia - Malice.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/7 - Necromantia - Circle of Burned Doves.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/IV- Malice/8 - Necromantia - Mordor.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/People of the Sea/1 - Necromantia - People of the Sea.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/People of the Sea/2 - Necromantia - My World, Your Hell.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Promo Tape 1990/1 - Necromantia - Family Of Dog (The Feast Of Ghouls).mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Promo Tape 1990/2 - Necromantia - De Magia Veterum (The Dawn Of Utter Darkness).mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Promo Tape 1990/3 - Necromantia - Faceles Gods.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Promo Tape 1990/4 - Necromantia - Evil Prayers.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Promo Tape 1990/5 - Necromantia - Lycanthropia.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Promo Tape 1990/6 - Necromantia - La Mort.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/1 - Necromantia - Devilskin.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/2 - Necromantia - Black Mirror.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/3 - Necromantia - Pretender to the Throne (Opus I- The Usurper’s Spawn).flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/4 - Necromantia - The Arcane Light of Hecate.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/5 - Necromantia - Scarlet Witching Dreams.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/6 - Necromantia - The Serpent and the Pentagram.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Scarlet Evil Witching Black/7 - Necromantia - Pretender to the Throne (Opus II- Battle at the Netherworld).flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/I - Necromantia - The Sound Of Lucifer Storming Heaven.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/II - Necromantia - Order Of The Black Sphinx.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/III - Necromantia - For The Elder Magi- I-Eibon The Necromancer.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/IV - Necromantia - Architecture Of Exquisite Madness.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/V - Necromantia - Knights Of The Black And White Eagle.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/VI - Necromantia - The Invisible Empire.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/VII - Necromantia - Hellseher.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/VIII - Necromantia - Les Litanies De Satan-Act II- From Hell.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/1 - Necromantia - The Sound Of Lucifer Storming Heaven.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/2 - Necromantia - Order Of The Black Sphinx.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/3 - Necromantia - For The Elder Magi- I‐Eibon The Necromancer.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/4 - Necromantia - Architecture Of Exquisite Madness.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/5 - Necromantia - Knights Of The Black And White Eagle.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/6 - Necromantia - The Invisible Empire.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/7 - Necromantia - HellSeher.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/The Sound Of Lucifer Storming Heaven/8 - Necromantia - Les Litanies De Satan - Act Ii- From Hell.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/1 - Necromantia - Daemonocentric.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/2 - Necromantia - And The Shadows Wept....flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/3 - Necromantia - Give The Devil His Due.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/4 - Necromantia - Inferno.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/5 - Necromantia - Eldritch.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/6 - Necromantia - To The Depths We Descend....flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/7 - Necromantia - Lord Of The Abyss MMXXI.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/To The Depths We Descend/8 - Necromantia - The Warlock MMXXI.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Unholy Relics of the Ancients/1 - Necromantia - Magnum Arcanum.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Unholy Relics of the Ancients/2 - Necromantia - People of the Sea.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Unholy Relics of the Ancients/3 - Necromantia - Family of Dog 1992.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Unholy Relics of the Ancients/4 - Necromantia - Faceless Gods 2021.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/1 - Necromantia - Lord of the Abyss.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/2 - Necromantia - The Feast of Ghouls.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/3 - Necromantia - Evil Prayers.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/4 - Necromantia - Lycanthropia.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/5 - Necromantia - De Magia Veterum.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/6 - Necromantia - Faceless Gods.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Preloading song: music-library/USB/WD-2TB/Necromantia/Vampiric Rituals/7 - Necromantia - La Mort.mp3 Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Ancient Pride/1 - Necromantia - The Shaman.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Ancient%20Pride/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FAncient%20Pride%2F1%20-%20Necromantia%20-%20The%20Shaman.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Ancient Pride/1 - Necromantia - The Shaman.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Ancient Pride/2 - Necromantia - Ancient Pride.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Ancient%20Pride/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FAncient%20Pride%2F2%20-%20Necromantia%20-%20Ancient%20Pride.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Ancient Pride/2 - Necromantia - Ancient Pride.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Ancient Pride/3 - Necromantia - For the Light of My Darkness.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Ancient%20Pride/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FAncient%20Pride%2F3%20-%20Necromantia%20-%20For%20the%20Light%20of%20My%20Darkness.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Ancient Pride/3 - Necromantia - For the Light of My Darkness.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Ancient Pride/4 - Necromantia - Each Dawn I Die.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Ancient%20Pride/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FAncient%20Pride%2F4%20-%20Necromantia%20-%20Each%20Dawn%20I%20Die.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Ancient Pride/4 - Necromantia - Each Dawn I Die.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Ancient Pride/5 - Necromantia - The Number of the Beast.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Ancient%20Pride/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FAncient%20Pride%2F5%20-%20Necromantia%20-%20The%20Number%20of%20the%20Beast.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Ancient Pride/5 - Necromantia - The Number of the Beast.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Ancient Pride/6 - Necromantia - Spiritforms of the Psychomancer.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Ancient%20Pride/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FAncient%20Pride%2F6%20-%20Necromantia%20-%20Spiritforms%20of%20the%20Psychomancer.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Ancient Pride/6 - Necromantia - Spiritforms of the Psychomancer.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/1 - Necromantia - My World, Your Hell.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F1%20-%20Necromantia%20-%20My%20World%2C%20Your%20Hell.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/1 - Necromantia - My World, Your Hell.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/2 - Necromantia - Death Rider.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F2%20-%20Necromantia%20-%20Death%20Rider.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/2 - Necromantia - Death Rider.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/3 - Necromantia - The Number of the Beast.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F3%20-%20Necromantia%20-%20The%20Number%20of%20the%20Beast.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/3 - Necromantia - The Number of the Beast.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/4 - Necromantia - The Demon’s Whip.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F4%20-%20Necromantia%20-%20The%20Demon%E2%80%99s%20Whip.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/4 - Necromantia - The Demon’s Whip.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/1 - Necromantia - Those Who Never Sleep.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F1%20-%20Necromantia%20-%20Those%20Who%20Never%20Sleep.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/1 - Necromantia - Those Who Never Sleep.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/2 - Necromantia - Murder, Magic and Tears.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F2%20-%20Necromantia%20-%20Murder%2C%20Magic%20and%20Tears.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/2 - Necromantia - Murder, Magic and Tears.flac Jan 28 12:28:23 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/3 - Necromantia - Ancient Pride.flac in service mpd Jan 28 12:28:23 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F3%20-%20Necromantia%20-%20Ancient%20Pride.flac&metadata=false Jan 28 12:28:23 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/3 - Necromantia - Ancient Pride.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/4 - Necromantia - For the Light of My Darkness.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F4%20-%20Necromantia%20-%20For%20the%20Light%20of%20My%20Darkness.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/4 - Necromantia - For the Light of My Darkness.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/5 - Necromantia - The Black Mirror.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F5%20-%20Necromantia%20-%20The%20Black%20Mirror.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/5 - Necromantia - The Black Mirror.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/6 - Necromantia - Pretender to the Throne Part 1- The Usurpers Spawn.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F6%20-%20Necromantia%20-%20Pretender%20to%20the%20Throne%20Part%201-%20The%20Usurpers%20Spawn.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/6 - Necromantia - Pretender to the Throne Part 1- The Usurpers Spawn.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/7 - Necromantia - Scarlet Witching Dreams.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F7%20-%20Necromantia%20-%20Scarlet%20Witching%20Dreams.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/7 - Necromantia - Scarlet Witching Dreams.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/8 - Necromantia - Les Litanies de Satan.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F8%20-%20Necromantia%20-%20Les%20Litanies%20de%20Satan.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/8 - Necromantia - Les Litanies de Satan.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/9 - Necromantia - Lord of the Abyss.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Covering%20Evil%3A%2012%20Years%20Doing%20the%20Devil's%20Work/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCovering%20Evil-%2012%20Years%20Doing%20the%20Devil's%20Work%2F9%20-%20Necromantia%20-%20Lord%20of%20the%20Abyss.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Covering Evil- 12 Years Doing the Devil's Work/9 - Necromantia - Lord of the Abyss.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F1%20-%20Necromantia%20-%20The%20Vampire%20Lord%20Speaks....flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/2 - Necromantia - The Warlock.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F2%20-%20Necromantia%20-%20The%20Warlock.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/2 - Necromantia - The Warlock.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/3 - Necromantia - Last Song for Valdezie.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F3%20-%20Necromantia%20-%20Last%20Song%20for%20Valdezie.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/3 - Necromantia - Last Song for Valdezie.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/4 - Necromantia - Unchaining the Wolf (At War...).flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F4%20-%20Necromantia%20-%20Unchaining%20the%20Wolf%20(At%20War...).flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/4 - Necromantia - Unchaining the Wolf (At War...).flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/5 - Necromantia - Les Litanies de Satan.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F5%20-%20Necromantia%20-%20Les%20Litanies%20de%20Satan.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/5 - Necromantia - Les Litanies de Satan.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/6 - Necromantia - Lord of the Abyss.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F6%20-%20Necromantia%20-%20Lord%20of%20the%20Abyss.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/6 - Necromantia - Lord of the Abyss.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/7 - Necromantia - Tribes of the Moon.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F7%20-%20Necromantia%20-%20Tribes%20of%20the%20Moon.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/7 - Necromantia - Tribes of the Moon.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/Crossing the Fiery Path/8 - Necromantia - Death Rider.flac in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/Crossing%20the%20Fiery%20Path/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FCrossing%20the%20Fiery%20Path%2F8%20-%20Necromantia%20-%20Death%20Rider.flac&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/Crossing the Fiery Path/8 - Necromantia - Death Rider.flac Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/De Magia Veterum/1 - Necromantia - Family Of Dog (The Feast Of Ghouls).mp3 in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/De%20Magia%20Veterum/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FDe%20Magia%20Veterum%2F1%20-%20Necromantia%20-%20Family%20Of%20Dog%20(The%20Feast%20Of%20Ghouls).mp3&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/De Magia Veterum/1 - Necromantia - Family Of Dog (The Feast Of Ghouls).mp3 Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/De Magia Veterum/2 - Necromantia - De Magia Veterum.mp3 in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/De%20Magia%20Veterum/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FDe%20Magia%20Veterum%2F2%20-%20Necromantia%20-%20De%20Magia%20Veterum.mp3&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/De Magia Veterum/2 - Necromantia - De Magia Veterum.mp3 Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/De Magia Veterum/3 - Necromantia - Faceless Gods.mp3 in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/De%20Magia%20Veterum/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FDe%20Magia%20Veterum%2F3%20-%20Necromantia%20-%20Faceless%20Gods.mp3&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/De Magia Veterum/3 - Necromantia - Faceless Gods.mp3 Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/De Magia Veterum/4 - Necromantia - Evil Prayers.mp3 in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/De%20Magia%20Veterum/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FDe%20Magia%20Veterum%2F4%20-%20Necromantia%20-%20Evil%20Prayers.mp3&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/De Magia Veterum/4 - Necromantia - Evil Prayers.mp3 Jan 28 12:28:24 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 12:28:24 volumiopi4 volumio[1201]: info: CURURI: genres://%CE%95%CE%BB%CE%BB%CE%AC%CF%82/Necromantia/Crossing%20the%20Fiery%20Path Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/De Magia Veterum/5 - Necromantia - Lycanthropia.mp3 in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/De%20Magia%20Veterum/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FDe%20Magia%20Veterum%2F5%20-%20Necromantia%20-%20Lycanthropia.mp3&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/De Magia Veterum/5 - Necromantia - Lycanthropia.mp3 Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Exploding uri music-library/USB/WD-2TB/Necromantia/De Magia Veterum/6 - Necromantia - La Mort.mp3 in service mpd Jan 28 12:28:24 volumiopi4 volumio[1201]: info: ALBUMART /albumart?cacheid=228&web=Necromantia/De%20Magia%20Veterum/extralarge&path=%2Fmnt%2FUSB%2FWD-2TB%2FNecromantia%2FDe%20Magia%20Veterum%2F6%20-%20Necromantia%20-%20La%20Mort.mp3&metadata=false Jan 28 12:28:24 volumiopi4 volumio[1201]: info: URI /mnt/USB/WD-2TB/Necromantia/De Magia Veterum/6 - Necromantia - La Mort.mp3 Jan 28 12:28:24 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:25 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:25 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::ClearQueue Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::serviceStop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 1 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::serviceStop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: [1769621306375] ControllerQobuz::stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::stop Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::clearPlayQueue Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::saveQueue Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushQueue Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::addQueueItems Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::addQueueItems Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Preload queue cleared Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Adding Item to queue: genres://%CE%95%CE%BB%CE%BB%CE%AC%CF%82/Necromantia/Crossing%20the%20Fiery%20Path Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Exploding uri genres://%CE%95%CE%BB%CE%BB%CE%AC%CF%82/Necromantia/Crossing%20the%20Fiery%20Path in service mpd Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Jan 28 12:28:26 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 50 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushQueue Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::saveQueue Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::updateTrackBlock Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrackBlock Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPlay Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::play index 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::play index undefined Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::startPlaybackTimer Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::clearAddPlayTracks USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand status took 12 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand stop took 4 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Jan 28 12:28:26 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand clear took 8 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac" Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: 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":"138932075","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/138932075","trackType":"qobuz"} Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: No code Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ------------------------------ 47ms Jan 28 12:28:26 volumiopi4 volumio[1201]: error: updateQueue error: null Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Jan 28 12:28:26 volumiopi4 volumio[1201]: ---------------------------- MPD announces system playlist update Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Ignoring MPD Status Update Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ------------------------------ 31ms Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand add "USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac" took 26 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Jan 28 12:28:26 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ------------------------------ 28ms Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand play took 27 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:28:26 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Jan 28 12:28:26 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand status took 38 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand status took 2 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":105,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Vampire Lord Speaks...","artist":"Necromantia","album":"Crossing the Fiery Path","uri":"USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac","trackType":"flac"} Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ------------------------------ 44ms Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:26 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":105,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"The Vampire Lord Speaks...","artist":"Necromantia","album":"Crossing the Fiery Path","uri":"USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac","trackType":"flac"} Jan 28 12:28:26 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService play Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Received an update from plugin. extracting info from payload Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:26 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:26 volumiopi4 volumio[1201]: info: ------------------------------ 27ms Jan 28 12:28:26 volumiopi4 volumio[1201]: error: FusionDsp - WebSocket error: [object Object] Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 12:28:26 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:26 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:27 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:27 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:27 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:27 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:27 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:27 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:28 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:28 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:28 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:28 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:30 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:30 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:30 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:30 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:31 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:31 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:32 volumiopi4 volumio[1201]: info: Executing endpoint metavolumio Jan 28 12:28:32 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 12:28:32 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 12:28:32 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::pause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::servicePause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: ControllerMpd::pause Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand pause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: sendMpdCommand pause took 1 milliseconds Jan 28 12:28:40 volumiopi4 volumio[1201]: info: Jan 28 12:28:40 volumiopi4 volumio[1201]: ---------------------------- MPD announces state update: player Jan 28 12:28:40 volumiopi4 volumio[1201]: info: ControllerMpd::getState Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:28:40 volumiopi4 volumio[1201]: info: sendMpdCommand status took 0 milliseconds Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseState Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:28:40 volumiopi4 volumio[1201]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:28:40 volumiopi4 volumio[1201]: info: ControllerMpd::pushState Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::servicePushState Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1715,"duration":105,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"The Vampire Lord Speaks...","artist":"Necromantia","album":"Crossing the Fiery Path","uri":"USB/WD-2TB/Necromantia/Crossing the Fiery Path/1 - Necromantia - The Vampire Lord Speaks....flac","trackType":"flac"} Jan 28 12:28:40 volumiopi4 volumio[1201]: verbose: CURRENT POSITION 0 Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState stateService pause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::syncState currentStatus pause Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::pushState Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioPushState Jan 28 12:28:40 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Jan 28 12:28:40 volumiopi4 volumio[1201]: info: MRS: Pushing multiroomSync output Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:40 volumiopi4 volumio[1201]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:28:40 volumiopi4 volumio[1201]: info: ------------------------------ 15ms Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 12:28:43 volumiopi4 volumio[1201]: info: Discovery: Getting this device information Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 12:28:43 volumiopi4 volumio[1201]: verbose: New Socket.io Connection to 192.168.1.159:3000 from 192.168.1.71 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 12:28:43 volumiopi4 volumio[1201]: info: Discovery: Getting this device information Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::volumioGetState Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CorePlayQueue::getTrack 0 Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 12:28:43 volumiopi4 volumio[1201]: verbose: New Socket.io Connection to 192.168.1.161:3000 from 192.168.1.71 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 12:28:43 volumiopi4 volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 12:28:46 volumiopi4 volumio[1201]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jan 28 12:28:48 volumiopi4 volumio[1201]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Jan 28 12:28:48 volumiopi4 volumio[1201]: info: FusionDsp - Stopping FusionDsp service Jan 28 12:28:48 volumiopi4 volumio[1201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 12:28:48 volumiopi4 volumio[1201]: TypeError: Cannot read property 'stop' of null Jan 28 12:28:48 volumiopi4 volumio[1201]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jan 28 12:28:48 volumiopi4 volumio[1201]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 28 12:28:48 volumiopi4 volumio[1201]: at /volumio/app/pluginmanager.js:956:12 Jan 28 12:28:48 volumiopi4 volumio[1201]: at ChildProcess.exithandler (child_process.js:299:7) Jan 28 12:28:48 volumiopi4 volumio[1201]: at ChildProcess.emit (events.js:315:20) Jan 28 12:28:48 volumiopi4 volumio[1201]: at maybeClose (internal/child_process.js:1048:16) Jan 28 12:28:48 volumiopi4 volumio[1201]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Jan 28 12:28:48 volumiopi4 volumio[1201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 12:28:48 volumiopi4 sudo[8433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 12:27 Jan 28 12:28:48 volumiopi4 sudo[8433]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"