-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2025-06-14 21:46:26 CEST. -- Jun 14 21:45:03 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:45:03 volumio-k volumio[904]: info: FusionDsp - no preset used Jun 14 21:45:03 volumio-k volumio[904]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Basiseinstellung Keller.json Jun 14 21:45:10 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:12 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:12 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:12 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:12 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:12 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:12 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:14 volumio-k volumio[904]: info: CoreCommandRouter::volumioPlay Jun 14 21:45:14 volumio-k volumio[904]: info: CoreStateMachine::play index undefined Jun 14 21:45:14 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 21:45:14 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:14 volumio-k volumio[904]: info: CoreStateMachine::startPlaybackTimer Jun 14 21:45:14 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:14 volumio-k volumio[904]: info: [1749930314874] ControllerTidal::resume Jun 14 21:45:14 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 21:45:14 volumio-k volumio[904]: info: ControllerMpd::resume Jun 14 21:45:14 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand play Jun 14 21:45:14 volumio-k volumio[904]: info: sendMpdCommand play took 1 milliseconds Jun 14 21:45:18 volumio-k volumio[904]: info: CoreCommandRouter::volumioPlay Jun 14 21:45:18 volumio-k volumio[904]: info: CoreStateMachine::play index undefined Jun 14 21:45:18 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 21:45:18 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:18 volumio-k volumio[904]: info: CoreStateMachine::startPlaybackTimer Jun 14 21:45:18 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:18 volumio-k volumio[904]: info: [1749930318731] ControllerTidal::resume Jun 14 21:45:18 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 21:45:18 volumio-k volumio[904]: info: ControllerMpd::resume Jun 14 21:45:18 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand play Jun 14 21:45:18 volumio-k volumio[904]: info: sendMpdCommand play took 2 milliseconds Jun 14 21:45:32 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 14 21:45:32 volumio-k volumio[904]: info: browseTIDALUri took 95 milliseconds Jun 14 21:45:33 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:33 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:37 volumio-k volumio[904]: Searching plugin music_service/tidal Jun 14 21:45:37 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 14 21:45:37 volumio-k volumio[904]: info: searchTIDALUri took 399 milliseconds Jun 14 21:45:37 volumio-k volumio[904]: info: search took 402 milliseconds Jun 14 21:45:37 volumio-k volumio[904]: info: All search sources collected, pushing search results Jun 14 21:45:39 volumio-k volumio[904]: Searching plugin music_service/tidal Jun 14 21:45:39 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 14 21:45:39 volumio-k volumio[904]: info: searchTIDALUri took 131 milliseconds Jun 14 21:45:39 volumio-k volumio[904]: info: search took 133 milliseconds Jun 14 21:45:39 volumio-k volumio[904]: info: All search sources collected, pushing search results Jun 14 21:45:42 volumio-k volumio[904]: Searching plugin music_service/tidal Jun 14 21:45:42 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 14 21:45:42 volumio-k volumio[904]: info: searchTIDALUri took 235 milliseconds Jun 14 21:45:42 volumio-k volumio[904]: info: search took 237 milliseconds Jun 14 21:45:42 volumio-k volumio[904]: info: All search sources collected, pushing search results Jun 14 21:45:44 volumio-k volumio[904]: Searching plugin music_service/tidal Jun 14 21:45:44 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 14 21:45:45 volumio-k volumio[904]: info: searchTIDALUri took 158 milliseconds Jun 14 21:45:45 volumio-k volumio[904]: info: search took 159 milliseconds Jun 14 21:45:45 volumio-k volumio[904]: info: All search sources collected, pushing search results Jun 14 21:45:46 volumio-k volumio[904]: Searching plugin music_service/tidal Jun 14 21:45:46 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 14 21:45:46 volumio-k volumio[904]: info: searchTIDALUri took 351 milliseconds Jun 14 21:45:46 volumio-k volumio[904]: info: search took 352 milliseconds Jun 14 21:45:46 volumio-k volumio[904]: info: All search sources collected, pushing search results Jun 14 21:45:47 volumio-k volumio[904]: Searching plugin music_service/tidal Jun 14 21:45:47 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 14 21:45:47 volumio-k volumio[904]: info: searchTIDALUri took 121 milliseconds Jun 14 21:45:47 volumio-k volumio[904]: info: search took 123 milliseconds Jun 14 21:45:47 volumio-k volumio[904]: info: All search sources collected, pushing search results Jun 14 21:45:48 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 14 21:45:49 volumio-k volumio[904]: info: browseTIDALUri took 209 milliseconds Jun 14 21:45:49 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:49 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:49 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:49 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:49 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 14 21:45:52 volumio-k volumio[904]: info: browseTIDALUri took 172 milliseconds Jun 14 21:45:52 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:52 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:52 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:53 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:53 volumio-k volumio[904]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 14 21:45:53 volumio-k volumio[904]: info: CoreStateMachine::ClearQueue Jun 14 21:45:53 volumio-k volumio[904]: info: CoreStateMachine::stop Jun 14 21:45:53 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 21:45:53 volumio-k volumio[904]: info: CoreStateMachine::updateTrackBlock Jun 14 21:45:53 volumio-k volumio[904]: info: CorePlayQueue::getTrackBlock Jun 14 21:45:53 volumio-k volumio[904]: info: CoreStateMachine::stPlaybackTimer Jun 14 21:45:53 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:53 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:53 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:45:53 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:54 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:54 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::serviceStop Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::serviceStop Jun 14 21:45:54 volumio-k volumio[904]: info: [1749930354024] ControllerTidal::stop Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::stop Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::clearPlayQueue Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::saveQueue Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushQueue Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::addQueueItems Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::addQueueItems Jun 14 21:45:54 volumio-k volumio[904]: info: Preload queue cleared Jun 14 21:45:54 volumio-k volumio[904]: info: Adding Item to queue: tidal://album/3711036 Jun 14 21:45:54 volumio-k volumio[904]: info: Using cached record of: tidal://album/3711036 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushQueue Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::saveQueue Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::updateTrackBlock Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrackBlock Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioPlay Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::play index 0 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::stop Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::play index undefined Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::startPlaybackTimer Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:54 volumio-k volumio[904]: info: [1749930354077] ControllerTidal::clearAddPlayTrack Jun 14 21:45:54 volumio-k volumio[904]: info: Getting stream with soundQuality HI_RES Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand stop took 208 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand stop took 154 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces system playlist update Jun 14 21:45:54 volumio-k volumio[904]: info: Ignoring MPD Status Update Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand clear took 8 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3711037&soundQuality=HI_RES" Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces system playlist update Jun 14 21:45:54 volumio-k volumio[904]: info: Ignoring MPD Status Update Jun 14 21:45:54 volumio-k volumio[904]: error: updateQueue error: null Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces system playlist update Jun 14 21:45:54 volumio-k volumio[904]: info: Ignoring MPD Status Update Jun 14 21:45:54 volumio-k volumio[904]: info: ------------------------------ 21ms Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3711037&soundQuality=HI_RES" took 15 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: ------------------------------ 10ms Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand play Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces system playlist update Jun 14 21:45:54 volumio-k volumio[904]: info: Ignoring MPD Status Update Jun 14 21:45:54 volumio-k volumio[904]: STREAMING PROXY: Handling url /?data=tidal://song/3711037&soundQuality=HI_RES Jun 14 21:45:54 volumio-k volumio[904]: info: ------------------------------ 19ms Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand play took 13 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: ------------------------------ 10ms Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:54 volumio-k volumio[904]: info: Executing endpoint getStreamUrltidal Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 14 21:45:54 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:54 volumio-k volumio[904]: info: getStreamUrl took 274 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidhNzNjMDQyOGVkNGE2OWQwM2M2YjdjZjM4YWVmYmE5NF82MS5tcDQ/0.flac?token=1749933750~MGIxNDY0Nzc3Yzg5NzQyZDU3MzYyZDYwZmExNjBmMDlmMzNlODVkYg== Jun 14 21:45:54 volumio-k volumio[904]: STREAMING PROXY: Response: 200, length: 50073410 Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces state update: player Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::getState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand status Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces state update: player Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::getState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand status Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:54 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces state update: player Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand status took 91 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::getState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand status Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand status took 89 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::parseState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::parseState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 21:45:54 volumio-k volumio[904]: info: Jun 14 21:45:54 volumio-k volumio[904]: ---------------------------- MPD announces state update: player Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::getState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand status Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand status took 28 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: info: sendMpdCommand status took 10 milliseconds Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::parseState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::parseTrackInfo Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::parseTrackInfo Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::parseState Jun 14 21:45:54 volumio-k volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::pushState Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::servicePushState Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:54 volumio-k volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":543,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"3711037&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3711037&soundQuality=HI_RES","trackType":"tidal"} Jun 14 21:45:54 volumio-k volumio[904]: verbose: CURRENT POSITION 0 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::syncState stateService play Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::syncState currentStatus stop Jun 14 21:45:54 volumio-k volumio[904]: info: ControllerMpd::pushState Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::servicePushState Jun 14 21:45:54 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:54 volumio-k volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":543,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"94 Kbps","isStreaming":false,"title":"3711037&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3711037&soundQuality=HI_RES","trackType":"tidal"} Jun 14 21:45:54 volumio-k volumio[904]: verbose: CURRENT POSITION 0 Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::syncState stateService play Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::syncState currentStatus play Jun 14 21:45:54 volumio-k volumio[904]: info: Received an update from plugin. extracting info from payload Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:54 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:54 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:54 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:54 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:54 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:54 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:54 volumio-k volumio[904]: info: ------------------------------ 191ms Jun 14 21:45:55 volumio-k volumio[904]: info: ------------------------------ 227ms Jun 14 21:45:55 volumio-k volumio[904]: info: sendMpdCommand playlistinfo took 106 milliseconds Jun 14 21:45:55 volumio-k volumio[904]: info: sendMpdCommand playlistinfo took 100 milliseconds Jun 14 21:45:55 volumio-k volumio[904]: verbose: ControllerMpd::parseTrackInfo Jun 14 21:45:55 volumio-k volumio[904]: info: ControllerMpd::pushState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::servicePushState Jun 14 21:45:55 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:55 volumio-k volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":543,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"3711037&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3711037&soundQuality=HI_RES","trackType":"tidal"} Jun 14 21:45:55 volumio-k volumio[904]: verbose: CURRENT POSITION 0 Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::syncState stateService play Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::syncState currentStatus play Jun 14 21:45:55 volumio-k volumio[904]: info: Received an update from plugin. extracting info from payload Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:55 volumio-k volumio[904]: info: ControllerMpd::pushState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::servicePushState Jun 14 21:45:55 volumio-k volumio[904]: info: CorePlayQueue::getTrack 0 Jun 14 21:45:55 volumio-k volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":543,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"509 Kbps","isStreaming":false,"title":"3711037&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3711037&soundQuality=HI_RES","trackType":"tidal"} Jun 14 21:45:55 volumio-k volumio[904]: verbose: CURRENT POSITION 0 Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::syncState stateService play Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::syncState currentStatus play Jun 14 21:45:55 volumio-k volumio[904]: info: Received an update from plugin. extracting info from payload Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:45:55 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:45:55 volumio-k volumio[904]: info: ------------------------------ 277ms Jun 14 21:45:55 volumio-k volumio[904]: info: ------------------------------ 255ms Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 86 gain applied 0.00 Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:45:55 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:45:55 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:45:57 volumio-k volumio[904]: error: Failed request for metavolumio API Jun 14 21:46:00 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume88 Jun 14 21:46:00 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:00 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:00 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:00 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:00 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:00 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 88 gain applied 0.00 Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 88 gain applied 0.00 Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 88 gain applied 0.00 Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:00 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume100 Jun 14 21:46:00 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:00 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:00 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:00 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:00 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:00 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:00 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:04 volumio-k volumio[904]: STREAMING PROXY: Client dropped request, destroying Jun 14 21:46:05 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume30 Jun 14 21:46:05 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:05 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:05 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:05 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:05 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:05 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 30 gain applied 10.22 Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 30 gain applied 10.22 Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 30 gain applied 10.22 Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:05 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume31 Jun 14 21:46:06 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:06 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:06 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:06 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:06 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:06 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 31 gain applied 9.71 Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 31 gain applied 9.71 Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 31 gain applied 9.71 Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume41 Jun 14 21:46:06 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:06 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:06 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:06 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:06 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:06 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 41 gain applied 4.60 Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 41 gain applied 4.60 Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 41 gain applied 4.60 Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:06 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume42 Jun 14 21:46:07 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 42 gain applied 4.09 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 42 gain applied 4.09 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 42 gain applied 4.09 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume49 Jun 14 21:46:07 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 49 gain applied 0.51 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 49 gain applied 0.51 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 49 gain applied 0.51 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume51 Jun 14 21:46:07 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 51 gain applied 0.00 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 51 gain applied 0.00 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 51 gain applied 0.00 Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:07 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume54 Jun 14 21:46:07 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:07 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:07 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 54 gain applied 0.00 Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 54 gain applied 0.00 Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 54 gain applied 0.00 Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:08 volumio-k volumio[904]: info: VolumeController::SetAlsaVolume100 Jun 14 21:46:08 volumio-k volumio[904]: info: CoreStateMachine::pushState Jun 14 21:46:08 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 21:46:08 volumio-k volumio[904]: info: CoreCommandRouter::volumioPushState Jun 14 21:46:08 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output update for this device Jun 14 21:46:08 volumio-k volumio[904]: info: MRS: Pushing multiroomSync output Jun 14 21:46:08 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - Loudness is ON true Jun 14 21:46:08 volumio-k volumio[904]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 14 21:46:15 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetState Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:46:17 volumio-k volumio[904]: info: Retrieving Cloud Streaming UI Jun 14 21:46:17 volumio-k volumio[904]: info: Getting Tidal Cloud Configuration Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:46:17 volumio-k volumio[904]: info: Getting Qobuz Cloud Configuration Jun 14 21:46:17 volumio-k volumio[904]: info: Asking plugin for UI Config Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:46:17 volumio-k volumio[904]: info: Getting Spotify Cloud Configuration Jun 14 21:46:17 volumio-k volumio[904]: info: Asking plugin for UI Config Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:46:17 volumio-k volumio[904]: info: Saving Spotify Acccount Jun 14 21:46:17 volumio-k volumio[904]: info: Got it Jun 14 21:46:17 volumio-k volumio[904]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 14 21:46:17 volumio-k volumio[904]: info: Got Tidal Cloud Configuration Jun 14 21:46:17 volumio-k volumio[904]: info: Got it Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 21:46:17 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:46:17 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:46:17 volumio-k volumio[904]: info: Executing endpoint metavolumio Jun 14 21:46:17 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 14 21:46:20 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 14 21:46:20 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 14 21:46:21 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 21:46:22 volumio-k volumio[904]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L volumio5.local. Jun 14 21:46:25 volumio-k volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 21:46:25 volumio-k volumio[904]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 21:46:25 volumio-k volumio[904]: Error: dns service error: unknown Jun 14 21:46:25 volumio-k volumio[904]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jun 14 21:46:25 volumio-k volumio[904]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 14 21:46:25 volumio-k volumio[904]: errorCode: -65537 Jun 14 21:46:25 volumio-k volumio[904]: } Jun 14 21:46:25 volumio-k volumio[904]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 21:46:26 volumio-k sudo[9872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 21:45 Jun 14 21:46:26 volumio-k sudo[9872]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"