-- Logs begin at Fri 2025-02-21 11:55:27 CET, end at Mon 2025-02-24 21:43:11 CET. -- Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 21:42:00 volumio volumio[4015]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 24 21:42:00 volumio volumio[4015]: info: Received Get System Info Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:00 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:00 volumio volumio[4015]: info: Listing playlists Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 21:42:00 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 21:42:00 volumio volumio[4015]: info: explodeTIDALUri took 54494 milliseconds Feb 24 21:42:00 volumio volumio[4015]: info: explodeTIDALUri took 54529 milliseconds Feb 24 21:42:00 volumio volumio[4015]: info: explodeTIDALUri took 54530 milliseconds Feb 24 21:42:00 volumio volumio[4015]: info: explodeTIDALUri took 54546 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55417 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55413 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55412 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55421 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55423 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55429 milliseconds Feb 24 21:42:01 volumio volumio[4015]: info: explodeTIDALUri took 55438 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 21:42:02 volumio volumio[4015]: info: Received Get System Info Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:02 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:02 volumio volumio[4015]: info: CoreCommandRouter::volumioPlay Feb 24 21:42:02 volumio volumio[4015]: info: CoreStateMachine::play index undefined Feb 24 21:42:02 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:02 volumio volumio[4015]: info: CorePlayQueue::getTrack 0 Feb 24 21:42:02 volumio volumio[4015]: info: CoreStateMachine::startPlaybackTimer Feb 24 21:42:02 volumio volumio[4015]: info: CorePlayQueue::getTrack 0 Feb 24 21:42:02 volumio volumio[4015]: info: [1740429722724] ControllerTidal::clearAddPlayTrack Feb 24 21:42:02 volumio volumio[4015]: info: Getting stream with soundQuality LOSSLESS Feb 24 21:42:02 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56517 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56554 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56559 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56557 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56558 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56565 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56548 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56551 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56553 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56558 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56560 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56557 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56552 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56561 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56564 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56563 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56564 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56572 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56573 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56579 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56582 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56589 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56594 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56593 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56593 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56594 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56598 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56600 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56601 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56603 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56608 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56614 milliseconds Feb 24 21:42:02 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [4012222] not found"} Feb 24 21:42:02 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/4012222 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [4012222] not found"} Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56616 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56625 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56632 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56636 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56645 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56646 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56652 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56655 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56657 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56662 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56665 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56666 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56671 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56678 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56681 milliseconds Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56682 milliseconds Feb 24 21:42:02 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [161252675] not found"} Feb 24 21:42:02 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/161252675 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [161252675] not found"} Feb 24 21:42:02 volumio volumio[4015]: info: explodeTIDALUri took 56695 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56685 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56680 milliseconds Feb 24 21:42:03 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [178588660] not found"} Feb 24 21:42:03 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/178588660 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [178588660] not found"} Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56681 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56825 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56818 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56835 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56830 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56831 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56833 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56839 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56846 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56851 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56863 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56866 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56876 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56877 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56880 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56885 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56886 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56886 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 56887 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 57674 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 57680 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: explodeTIDALUri took 57690 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: Feb 24 21:42:03 volumio volumio[4015]: ---------------------------- MPD announces state update: player Feb 24 21:42:03 volumio volumio[4015]: info: sendMpdCommand stop took 1260 milliseconds Feb 24 21:42:03 volumio volumio[4015]: info: ControllerMpd::getState Feb 24 21:42:03 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand status Feb 24 21:42:03 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57717 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57717 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57724 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57721 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57725 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57713 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57723 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57723 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57728 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57731 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57747 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57839 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57833 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57785 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57851 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57785 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57838 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57792 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57797 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57803 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57809 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57891 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57885 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57923 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57900 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57891 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57879 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57930 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57889 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57947 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57899 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57884 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57910 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57918 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57971 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57932 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57929 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57980 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57928 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57999 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58023 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57949 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57992 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 57950 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: Feb 24 21:42:04 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:04 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand status took 514 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand clear took 514 milliseconds Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::parseState Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1653995&soundQuality=LOSSLESS" Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 21:42:04 volumio volumio[4015]: info: Received Get System Info Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:04 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrack 0 Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::volumioPlay Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::play index undefined Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::volumioNext Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::next Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrack 0 Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::startPlaybackTimer Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrack 0 Feb 24 21:42:04 volumio volumio[4015]: info: [1740429724534] ControllerTidal::clearAddPlayTrack Feb 24 21:42:04 volumio volumio[4015]: info: Getting stream with soundQuality LOSSLESS Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::play index undefined Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::startPlaybackTimer Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:04 volumio volumio[4015]: info: [1740429724540] ControllerTidal::clearAddPlayTrack Feb 24 21:42:04 volumio volumio[4015]: info: Getting stream with soundQuality LOSSLESS Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::updateTrackBlock Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrackBlock Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58389 milliseconds Feb 24 21:42:04 volumio volumio[4015]: error: updateQueue error: null Feb 24 21:42:04 volumio volumio[4015]: info: Feb 24 21:42:04 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:04 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 82ms Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand playlistinfo took 75 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1653995&soundQuality=LOSSLESS" took 74 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand stop took 48 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand stop took 44 milliseconds Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::parseTrackInfo Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand play Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 21:42:04 volumio volumio[4015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 612ms Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58219 milliseconds Feb 24 21:42:04 volumio volumio[4015]: STREAMING PROXY: Handling url /?data=tidal://song/1653995&soundQuality=LOSSLESS Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58230 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: Feb 24 21:42:04 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:04 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:04 volumio volumio[4015]: info: Feb 24 21:42:04 volumio volumio[4015]: ---------------------------- MPD announces state update: player Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 35ms Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand play took 24 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand clear took 23 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand clear took 22 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: ControllerMpd::getState Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand status Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1653995&soundQuality=LOSSLESS" Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318443301&soundQuality=LOSSLESS" Feb 24 21:42:04 volumio volumio[4015]: error: updateQueue error: null Feb 24 21:42:04 volumio volumio[4015]: info: Feb 24 21:42:04 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:04 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:04 volumio volumio[4015]: info: Feb 24 21:42:04 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:04 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 9ms Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand status took 8 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1653995&soundQuality=LOSSLESS" took 8 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318443301&soundQuality=LOSSLESS" took 9 milliseconds Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::parseState Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand play Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 21:42:04 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand play Feb 24 21:42:04 volumio volumio[4015]: info: ControllerMpd::pushState Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:04 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:04 volumio volumio[4015]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 24 21:42:04 volumio volumio[4015]: verbose: CURRENT POSITION 716 Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::syncState stateService stop Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::syncState currentStatus stop Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:04 volumio volumio[4015]: info: No code Feb 24 21:42:04 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:04 volumio volumio[4015]: STREAMING PROXY: Handling url /?data=tidal://song/1653995&soundQuality=LOSSLESS Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 81ms Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58324 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 88ms Feb 24 21:42:04 volumio volumio[4015]: info: ------------------------------ 88ms Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand play took 84 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: sendMpdCommand play took 84 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58338 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58356 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58367 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58418 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58421 milliseconds Feb 24 21:42:04 volumio volumio[4015]: info: Executing endpoint getStreamUrltidal Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 24 21:42:04 volumio volumio[4015]: info: Executing endpoint getStreamUrltidal Feb 24 21:42:04 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 24 21:42:04 volumio volumio[4015]: info: explodeTIDALUri took 58487 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61083 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61099 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61103 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61117 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61121 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61130 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61096 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61102 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61105 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61112 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61106 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61121 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61124 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61130 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61143 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61143 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61158 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61168 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61177 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61187 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61192 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61199 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61204 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61203 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61209 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61211 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61220 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61227 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61238 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61246 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61251 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61255 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61249 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61354 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61357 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61363 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61371 milliseconds Feb 24 21:42:07 volumio volumio[4015]: info: explodeTIDALUri took 61378 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61531 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61964 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61965 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61968 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61975 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61977 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61980 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61991 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 61991 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62000 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62002 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62004 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62011 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62014 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62016 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62016 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62022 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62029 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62029 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62030 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62033 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62038 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62038 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: explodeTIDALUri took 62248 milliseconds Feb 24 21:42:08 volumio volumio[4015]: info: CoreCommandRouter::volumioPlay Feb 24 21:42:08 volumio volumio[4015]: info: CoreStateMachine::play index undefined Feb 24 21:42:08 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:08 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:08 volumio volumio[4015]: info: CoreStateMachine::startPlaybackTimer Feb 24 21:42:08 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:08 volumio volumio[4015]: info: [1740429728863] ControllerTidal::clearAddPlayTrack Feb 24 21:42:08 volumio volumio[4015]: info: Getting stream with soundQuality LOSSLESS Feb 24 21:42:08 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63020 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63024 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63026 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63028 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63033 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63039 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63040 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63041 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63047 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63049 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63058 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63060 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: CoreCommandRouter::volumioPlay Feb 24 21:42:09 volumio volumio[4015]: info: CoreStateMachine::play index undefined Feb 24 21:42:09 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:09 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:09 volumio volumio[4015]: info: CoreStateMachine::startPlaybackTimer Feb 24 21:42:09 volumio volumio[4015]: info: CorePlayQueue::getTrack 716 Feb 24 21:42:09 volumio volumio[4015]: info: [1740429729794] ControllerTidal::clearAddPlayTrack Feb 24 21:42:09 volumio volumio[4015]: info: Getting stream with soundQuality LOSSLESS Feb 24 21:42:09 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63075 milliseconds Feb 24 21:42:09 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [53124475] not found"} Feb 24 21:42:09 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/53124475 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [53124475] not found"} Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63090 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63090 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63092 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63099 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63097 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63103 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63105 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63109 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63115 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63112 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63118 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63126 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63130 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63135 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63139 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63142 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63143 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63148 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63152 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63151 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63151 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63081 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63088 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63089 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63097 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63107 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63186 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63119 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63120 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63122 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63124 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63132 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63128 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63134 milliseconds Feb 24 21:42:09 volumio volumio[4015]: info: explodeTIDALUri took 63137 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63140 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63143 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63142 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63150 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63156 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63157 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63158 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63161 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63182 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63187 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63192 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63193 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: Feb 24 21:42:10 volumio volumio[4015]: ---------------------------- MPD announces state update: player Feb 24 21:42:10 volumio volumio[4015]: info: sendMpdCommand stop took 1385 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: ControllerMpd::getState Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand status Feb 24 21:42:10 volumio volumio[4015]: info: sendMpdCommand stop took 453 milliseconds Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 21:42:10 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 21:42:10 volumio volumio[4015]: info: CoreCommandRouter::volumioNext Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::next Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::play index undefined Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:10 volumio volumio[4015]: info: CorePlayQueue::getTrack 467 Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::startPlaybackTimer Feb 24 21:42:10 volumio volumio[4015]: info: CorePlayQueue::getTrack 467 Feb 24 21:42:10 volumio volumio[4015]: info: [1740429730482] ControllerTidal::clearAddPlayTrack Feb 24 21:42:10 volumio volumio[4015]: info: Getting stream with soundQuality LOSSLESS Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 21:42:10 volumio volumio[4015]: info: CoreStateMachine::updateTrackBlock Feb 24 21:42:10 volumio volumio[4015]: info: CorePlayQueue::getTrackBlock Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63573 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63578 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63586 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63587 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63629 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63637 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63631 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63647 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63640 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: explodeTIDALUri took 63660 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: Feb 24 21:42:10 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:10 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:10 volumio volumio[4015]: info: sendMpdCommand status took 296 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: sendMpdCommand clear took 297 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: sendMpdCommand clear took 296 milliseconds Feb 24 21:42:10 volumio volumio[4015]: info: sendMpdCommand stop took 64 milliseconds Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::parseState Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318443301&soundQuality=LOSSLESS" Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318443301&soundQuality=LOSSLESS" Feb 24 21:42:10 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 21:42:11 volumio volumio[4015]: error: updateQueue error: null Feb 24 21:42:11 volumio volumio[4015]: info: Feb 24 21:42:11 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:11 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:11 volumio volumio[4015]: info: Feb 24 21:42:11 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:11 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:11 volumio volumio[4015]: info: Feb 24 21:42:11 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:11 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:11 volumio volumio[4015]: info: ------------------------------ 1193ms Feb 24 21:42:11 volumio volumio[4015]: info: sendMpdCommand playlistinfo took 1191 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318443301&soundQuality=LOSSLESS" took 1192 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318443301&soundQuality=LOSSLESS" took 1193 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: sendMpdCommand clear took 1192 milliseconds Feb 24 21:42:11 volumio volumio[4015]: verbose: ControllerMpd::parseTrackInfo Feb 24 21:42:11 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 21:42:11 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand play Feb 24 21:42:11 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 21:42:11 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand play Feb 24 21:42:11 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/12359468&soundQuality=LOSSLESS" Feb 24 21:42:11 volumio volumio[4015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 21:42:11 volumio volumio[4015]: info: ------------------------------ 1494ms Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65134 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 64845 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 64852 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65148 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 64860 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65157 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 64875 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65166 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 64886 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 64896 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65189 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65200 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65207 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65210 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65218 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65227 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65231 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65236 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65245 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65252 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65256 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65262 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65269 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65277 milliseconds Feb 24 21:42:11 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [34435648] not found"} Feb 24 21:42:11 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/34435648 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [34435648] not found"} Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65290 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65287 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65309 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65315 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65327 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65330 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65336 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65342 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65348 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65353 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65359 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65368 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65374 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65377 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65378 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65384 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65394 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65395 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65401 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65412 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65417 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65430 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65435 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65440 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65448 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65457 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65457 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65461 milliseconds Feb 24 21:42:11 volumio volumio[4015]: info: explodeTIDALUri took 65468 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65475 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65478 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65490 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65502 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65507 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65510 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65514 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65523 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65533 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65549 milliseconds Feb 24 21:42:12 volumio volumio[4015]: info: explodeTIDALUri took 65558 milliseconds Feb 24 21:42:13 volumio volumio[4015]: error: updateQueue error: null Feb 24 21:42:13 volumio volumio[4015]: error: updateQueue error: null Feb 24 21:42:13 volumio volumio[4015]: info: Feb 24 21:42:13 volumio volumio[4015]: ---------------------------- MPD announces system playlist update Feb 24 21:42:13 volumio volumio[4015]: info: Ignoring MPD Status Update Feb 24 21:42:13 volumio volumio[4015]: info: ------------------------------ 1298ms Feb 24 21:42:13 volumio volumio[4015]: info: ------------------------------ 1297ms Feb 24 21:42:13 volumio volumio[4015]: info: ------------------------------ 1296ms Feb 24 21:42:13 volumio volumio[4015]: info: sendMpdCommand play took 1291 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/12359468&soundQuality=LOSSLESS" took 1290 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 21:42:13 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand play Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66537 milliseconds Feb 24 21:42:13 volumio volumio[4015]: STREAMING PROXY: Handling url /?data=tidal://song/12359468&soundQuality=LOSSLESS Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66545 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66562 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66563 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66570 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66577 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66585 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66592 milliseconds Feb 24 21:42:13 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [186536899] not found"} Feb 24 21:42:13 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/186536899 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [186536899] not found"} Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66600 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66610 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66622 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66630 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66636 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66639 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66647 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66653 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66653 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66660 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66675 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66687 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66701 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66712 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66720 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66728 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66734 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66737 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66750 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66745 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66755 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66761 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66769 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66773 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66783 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66790 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66807 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66814 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66825 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66834 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66835 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66839 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66849 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66851 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66856 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66858 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66873 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66880 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66886 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66899 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66903 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66913 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66924 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66940 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66942 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66950 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: ------------------------------ 352ms Feb 24 21:42:13 volumio volumio[4015]: info: sendMpdCommand play took 351 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: Executing endpoint getStreamUrltidal Feb 24 21:42:13 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 66833 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 67077 milliseconds Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 67077 milliseconds Feb 24 21:42:13 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [121209446] not found"} Feb 24 21:42:13 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/121209446 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [121209446] not found"} Feb 24 21:42:13 volumio volumio[4015]: info: explodeTIDALUri took 67078 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67080 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67084 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67084 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67086 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67091 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67099 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67102 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67109 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67114 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67116 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67122 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67093 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67127 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67131 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67141 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67139 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67366 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67369 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67354 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67365 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67361 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67370 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67364 milliseconds Feb 24 21:42:14 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [6938557] not found"} Feb 24 21:42:14 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/6938557 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [6938557] not found"} Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67367 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67367 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67368 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67384 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67397 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67448 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67464 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67470 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67472 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67469 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67772 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67793 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67796 milliseconds Feb 24 21:42:14 volumio volumio[4015]: info: explodeTIDALUri took 67785 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68228 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68228 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68232 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68239 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68248 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68250 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68256 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68259 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68261 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68266 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68271 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68276 milliseconds Feb 24 21:42:15 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809282] not found"} Feb 24 21:42:15 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809282 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809282] not found"} Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68278 milliseconds Feb 24 21:42:15 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809280] not found"} Feb 24 21:42:15 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809280 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809280] not found"} Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68346 milliseconds Feb 24 21:42:15 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [69439394] not found"} Feb 24 21:42:15 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/69439394 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [69439394] not found"} Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68356 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68361 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68372 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68379 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68392 milliseconds Feb 24 21:42:15 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [231816603] not found"} Feb 24 21:42:15 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/231816603 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [231816603] not found"} Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68400 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68332 milliseconds Feb 24 21:42:15 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809284] not found"} Feb 24 21:42:15 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809284 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809284] not found"} Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68341 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68349 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68343 milliseconds Feb 24 21:42:15 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809285] not found"} Feb 24 21:42:15 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809285 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809285] not found"} Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68357 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68362 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68357 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68383 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68390 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68376 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68400 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68410 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68422 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68426 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68439 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68450 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68456 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68459 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68461 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68469 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68480 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68486 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68491 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68655 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68733 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68748 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68770 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68790 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68804 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68817 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68839 milliseconds Feb 24 21:42:15 volumio volumio[4015]: info: explodeTIDALUri took 68862 milliseconds Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 21:42:17 volumio volumio[4015]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 24 21:42:17 volumio volumio[4015]: info: Received Get System Version Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 21:42:17 volumio volumio[4015]: info: Received Get System Info Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:17 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:17 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:18 volumio volumio[4015]: info: explodeTIDALUri took 71819 milliseconds Feb 24 21:42:18 volumio volumio[4015]: info: explodeTIDALUri took 71835 milliseconds Feb 24 21:42:18 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:18 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:18 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:18 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:18 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:18 volumio volumio[4015]: info: explodeTIDALUri took 71852 milliseconds Feb 24 21:42:18 volumio volumio[4015]: info: explodeTIDALUri took 71854 milliseconds Feb 24 21:42:18 volumio volumio[4015]: info: explodeTIDALUri took 71856 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72068 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72069 milliseconds Feb 24 21:42:19 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [223967056] not found"} Feb 24 21:42:19 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/223967056 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [223967056] not found"} Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72069 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72136 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72135 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72149 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72248 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72249 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72252 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72255 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72258 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72268 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72267 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72267 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72268 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72300 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72301 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72270 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72273 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72279 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72283 milliseconds Feb 24 21:42:19 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [221962040] not found"} Feb 24 21:42:19 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/221962040 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [221962040] not found"} Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72284 milliseconds Feb 24 21:42:19 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [227197321] not found"} Feb 24 21:42:19 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/227197321 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [227197321] not found"} Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72286 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72292 milliseconds Feb 24 21:42:19 volumio volumio[4015]: info: explodeTIDALUri took 72296 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 72907 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 72906 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 72907 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 72911 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 72913 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 72915 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81143 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81145 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81147 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81152 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81161 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81161 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81171 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81169 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81165 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81164 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81168 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81172 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81172 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81169 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81166 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81171 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81177 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81179 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 81181 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 73013 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 73019 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 73028 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 73031 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 73034 milliseconds Feb 24 21:42:20 volumio volumio[4015]: info: explodeTIDALUri took 73039 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82306 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82299 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74142 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74148 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74155 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74138 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74139 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74138 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74144 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74141 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74142 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74145 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74152 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74149 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74148 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74149 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74154 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74156 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74155 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74165 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74165 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74175 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74178 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74177 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74183 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74189 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82431 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82431 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82430 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82439 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82439 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82439 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82442 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82451 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82453 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82463 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82468 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82466 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82475 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82479 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82475 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82476 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82481 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82486 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82488 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82491 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82496 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82501 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82502 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82498 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82512 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82508 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82517 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82521 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82526 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 82519 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74397 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74391 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74397 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74405 milliseconds Feb 24 21:42:21 volumio volumio[4015]: info: explodeTIDALUri took 74401 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74919 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74923 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74931 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74933 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74939 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74948 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74957 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74961 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74965 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74975 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74971 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 74975 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75020 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75037 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75050 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75052 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75064 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75067 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75068 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75070 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75079 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75082 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75082 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75582 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75587 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75597 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75597 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75598 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75601 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 83746 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: CoreCommandRouter::volumioPushQueue Feb 24 21:42:22 volumio volumio[4015]: info: CorePlayQueue::saveQueue Feb 24 21:42:22 volumio volumio[4015]: info: CoreStateMachine::updateTrackBlock Feb 24 21:42:22 volumio volumio[4015]: info: CorePlayQueue::getTrackBlock Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75701 milliseconds Feb 24 21:42:22 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [40516492] not found"} Feb 24 21:42:22 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/40516492 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [40516492] not found"} Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75572 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75578 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75582 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75595 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75601 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75619 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75634 milliseconds Feb 24 21:42:22 volumio volumio[4015]: info: explodeTIDALUri took 75640 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75655 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75659 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75667 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75678 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75684 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75695 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75698 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75705 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75712 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75714 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75719 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75730 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75735 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75748 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75754 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75767 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 75770 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76503 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76507 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76513 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76519 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76523 milliseconds Feb 24 21:42:23 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [68667816] not found"} Feb 24 21:42:23 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/68667816 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [68667816] not found"} Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76526 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76528 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76535 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76542 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76542 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76548 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76548 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76558 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76570 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76573 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76568 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76583 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76591 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76584 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76601 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76601 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76614 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76601 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76624 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76630 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76617 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76650 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76655 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76658 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76662 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76649 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76664 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76675 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76681 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76670 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76691 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76693 milliseconds Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76699 milliseconds Feb 24 21:42:23 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [99647471] not found"} Feb 24 21:42:23 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/99647471 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [99647471] not found"} Feb 24 21:42:23 volumio volumio[4015]: info: explodeTIDALUri took 76704 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 76774 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 76781 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 77123 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 77136 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 77151 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 77165 milliseconds Feb 24 21:42:24 volumio volumio[4015]: info: explodeTIDALUri took 77174 milliseconds Feb 24 21:42:25 volumio volumio[4015]: info: explodeTIDALUri took 78164 milliseconds Feb 24 21:42:25 volumio volumio[4015]: info: explodeTIDALUri took 78189 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82395 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82543 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82544 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82558 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82565 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82568 milliseconds Feb 24 21:42:29 volumio volumio[4015]: info: explodeTIDALUri took 82570 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82604 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82614 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82617 milliseconds Feb 24 21:42:30 volumio shairport-sync[5827]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 21:42:30 volumio shairport-sync[5827]: Dload Upload Total Spent Left Speed Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82623 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82627 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82625 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82633 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82636 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82638 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82644 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82650 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82651 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82652 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82653 milliseconds Feb 24 21:42:30 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82660 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82664 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82661 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82663 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82668 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82671 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82706 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82914 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82913 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: Airplay started streaming, receiving metadatas Feb 24 21:42:30 volumio volumio[4015]: info: CoreCommandRouter::volumioStop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:30 volumio volumio[4015]: info: Airplay started streaming, receiving metadatas Feb 24 21:42:30 volumio volumio[4015]: info: CorePlayQueue::getTrack 467 Feb 24 21:42:30 volumio volumio[4015]: info: CoreCommandRouter::volumioStop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:30 volumio volumio[4015]: info: Airplay started streaming, receiving metadatas Feb 24 21:42:30 volumio volumio[4015]: info: CorePlayQueue::getTrack 467 Feb 24 21:42:30 volumio volumio[4015]: info: CoreCommandRouter::volumioStop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:30 volumio volumio[4015]: info: Airplay started streaming, receiving metadatas Feb 24 21:42:30 volumio volumio[4015]: info: CorePlayQueue::getTrack 467 Feb 24 21:42:30 volumio volumio[4015]: info: CoreCommandRouter::volumioStop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:30 volumio volumio[4015]: info: Airplay started streaming, receiving metadatas Feb 24 21:42:30 volumio volumio[4015]: info: CorePlayQueue::getTrack 467 Feb 24 21:42:30 volumio volumio[4015]: info: CoreCommandRouter::volumioStop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::stop Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83070 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83071 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82589 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83082 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83084 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83084 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83086 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83092 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83097 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83100 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 83098 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: CoreCommandRouter::volumioPushQueue Feb 24 21:42:30 volumio volumio[4015]: info: CorePlayQueue::saveQueue Feb 24 21:42:30 volumio volumio[4015]: info: CoreStateMachine::updateTrackBlock Feb 24 21:42:30 volumio volumio[4015]: info: CorePlayQueue::getTrackBlock Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82810 milliseconds Feb 24 21:42:30 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [94662529] not found"} Feb 24 21:42:30 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/94662529 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [94662529] not found"} Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82808 milliseconds Feb 24 21:42:30 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [161382380] not found"} Feb 24 21:42:30 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/161382380 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [161382380] not found"} Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82796 milliseconds Feb 24 21:42:30 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [52638497] not found"} Feb 24 21:42:30 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/52638497 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [52638497] not found"} Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82861 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82865 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82879 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82871 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82880 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82883 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82886 milliseconds Feb 24 21:42:30 volumio volumio[4015]: info: explodeTIDALUri took 82892 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83179 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:31 volumio volumio[4015]: info: Feb 24 21:42:31 volumio volumio[4015]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 24 21:42:31 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 24 21:42:31 volumio volumio[4015]: info: Airplay playback start Feb 24 21:42:31 volumio shairport-sync[5827]: [316B blob data] Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83944 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83946 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83947 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83962 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83969 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83967 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83968 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83949 milliseconds Feb 24 21:42:31 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [55990084] not found"} Feb 24 21:42:31 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/55990084 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [55990084] not found"} Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83959 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83950 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83961 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83963 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83964 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83976 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83982 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83981 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83987 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83994 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 83998 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 84007 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 84013 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 84019 milliseconds Feb 24 21:42:31 volumio volumio[4015]: info: explodeTIDALUri took 84027 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84030 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84042 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84045 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84051 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84055 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84055 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84064 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84068 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84069 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84143 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84144 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84148 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84158 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84157 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84159 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84168 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84168 milliseconds Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84176 milliseconds Feb 24 21:42:32 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [114913974] not found"} Feb 24 21:42:32 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/114913974 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [114913974] not found"} Feb 24 21:42:32 volumio volumio[4015]: info: explodeTIDALUri took 84179 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:33 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:33 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:33 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89067 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89076 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89080 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89081 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89236 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89299 milliseconds Feb 24 21:42:33 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [94662529] not found"} Feb 24 21:42:33 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/94662529 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [94662529] not found"} Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89262 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89247 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89249 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 89109 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: CoreCommandRouter::volumioPushQueue Feb 24 21:42:33 volumio volumio[4015]: info: CorePlayQueue::saveQueue Feb 24 21:42:33 volumio volumio[4015]: info: CoreStateMachine::updateTrackBlock Feb 24 21:42:33 volumio volumio[4015]: info: CorePlayQueue::getTrackBlock Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85606 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85613 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85616 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85618 milliseconds Feb 24 21:42:33 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [4012222] not found"} Feb 24 21:42:33 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/4012222 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [4012222] not found"} Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85619 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85629 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85627 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85625 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85630 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85635 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85636 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85632 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85646 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85638 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85638 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85643 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85650 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85651 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85651 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85645 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85786 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85797 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85788 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85798 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85802 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85810 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85804 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85817 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85830 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85839 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85841 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85837 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85835 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85838 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85844 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85845 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85834 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85843 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85839 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85842 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85849 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85853 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85845 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85850 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85850 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85854 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85853 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85856 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85861 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85866 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85866 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85861 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85872 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85876 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85872 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85875 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85872 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85880 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85884 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85884 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85885 milliseconds Feb 24 21:42:33 volumio volumio[4015]: info: explodeTIDALUri took 85889 milliseconds Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:34 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87176 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87178 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87184 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87192 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87195 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87198 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87207 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87214 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87218 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87221 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87228 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87236 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87240 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87241 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87245 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87253 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87441 milliseconds Feb 24 21:42:35 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [161252675] not found"} Feb 24 21:42:35 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/161252675 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [161252675] not found"} Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87441 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87446 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87456 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87456 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87458 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87457 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87464 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87467 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87459 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87463 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87471 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87476 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87476 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87476 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87477 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87484 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87490 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87490 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87490 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87496 milliseconds Feb 24 21:42:35 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [178588660] not found"} Feb 24 21:42:35 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/178588660 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [178588660] not found"} Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87499 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87505 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87504 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87503 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87507 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87511 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87508 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87513 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87516 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87521 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87522 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87523 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87525 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87530 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87530 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87546 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87540 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87536 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87544 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87545 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87542 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87543 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87552 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87555 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87507 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87557 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87517 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87519 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87517 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87521 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87537 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87526 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87525 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87536 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87537 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87531 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87531 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87547 milliseconds Feb 24 21:42:35 volumio volumio[4015]: info: explodeTIDALUri took 87534 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88039 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88059 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88066 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88072 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88097 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88111 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88119 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88124 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88135 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88121 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88141 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88135 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88148 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88154 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88155 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88161 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88168 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88171 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88134 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88133 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88187 milliseconds Feb 24 21:42:36 volumio volumio[4015]: info: explodeTIDALUri took 88189 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89484 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89462 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89465 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89473 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89468 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89472 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89469 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89477 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89479 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89479 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89481 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89484 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89490 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89493 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89495 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89494 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89499 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89506 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89506 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89506 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89507 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89509 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89516 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89515 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89514 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89527 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89530 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89529 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89537 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89543 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89544 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89544 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89546 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89561 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89562 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89548 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89556 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89574 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89562 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89573 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89570 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89575 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89561 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89584 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89564 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89581 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89580 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89591 milliseconds Feb 24 21:42:37 volumio volumio[4015]: info: explodeTIDALUri took 89578 milliseconds Feb 24 21:42:37 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [34435648] not found"} Feb 24 21:42:37 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/34435648 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [34435648] not found"} Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89578 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89586 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89586 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89578 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89580 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89587 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89589 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89592 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89593 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89595 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89600 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89601 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89601 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89606 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89603 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89607 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89725 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89725 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89725 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89725 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89728 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89743 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89729 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89729 milliseconds Feb 24 21:42:38 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [186536899] not found"} Feb 24 21:42:38 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/186536899 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [186536899] not found"} Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89732 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: Executing endpoint metavolumio Feb 24 21:42:38 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 21:42:38 volumio volumio[4015]: info: Executing endpoint metavolumio Feb 24 21:42:38 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89788 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: Executing endpoint metavolumio Feb 24 21:42:38 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89756 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89770 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89790 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89817 milliseconds Feb 24 21:42:38 volumio volumio[4015]: info: explodeTIDALUri took 89828 milliseconds Feb 24 21:42:41 volumio volumio[4015]: info: explodeTIDALUri took 93443 milliseconds Feb 24 21:42:41 volumio volumio[4015]: info: explodeTIDALUri took 93444 milliseconds Feb 24 21:42:41 volumio volumio[4015]: info: explodeTIDALUri took 93413 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 93418 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 93420 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94256 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94259 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94275 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94273 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94281 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94284 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94288 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94294 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94302 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94309 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94307 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94315 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94317 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94323 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94329 milliseconds Feb 24 21:42:42 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [53124475] not found"} Feb 24 21:42:42 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/53124475 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [53124475] not found"} Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94331 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94333 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94334 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94346 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94351 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94357 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94361 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94364 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94371 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94377 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94379 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94382 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94384 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94391 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94397 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94399 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94402 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94408 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94411 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94422 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94425 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94430 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94428 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94442 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94446 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94447 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94449 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94456 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94463 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94468 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94482 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94487 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94495 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94489 milliseconds Feb 24 21:42:42 volumio volumio[4015]: info: explodeTIDALUri took 94497 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94503 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94509 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94521 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94527 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94531 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94537 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94543 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94548 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94548 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94556 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94555 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94569 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94578 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94573 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94582 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94589 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94514 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94677 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94706 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94721 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 94735 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95492 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95491 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95493 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95493 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95498 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95506 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95503 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95498 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95511 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: explodeTIDALUri took 95523 milliseconds Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:43 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95650 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95663 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95660 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95673 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95665 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95680 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95680 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95675 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95676 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95682 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: explodeTIDALUri took 95755 milliseconds Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:44 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:44 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:44 volumio volumio[4015]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.103 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Feb 24 21:42:45 volumio volumio[4015]: error: Failed request for metavolumio API Feb 24 21:42:45 volumio volumio[4015]: error: Failed request for metavolumio API Feb 24 21:42:45 volumio volumio[4015]: error: Failed request for metavolumio API Feb 24 21:42:45 volumio volumio[4015]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 21:42:45 volumio volumio[4015]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 24 21:42:45 volumio volumio[4015]: info: Received Get System Info Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:45 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:45 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:45 volumio volumio[4015]: info: Listing playlists Feb 24 21:42:46 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98123 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98123 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98139 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98138 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98135 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98162 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98167 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98173 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98164 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98147 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98147 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98186 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98259 milliseconds Feb 24 21:42:46 volumio volumio[4015]: info: explodeTIDALUri took 98331 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98428 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98506 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98497 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98500 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98525 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98524 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98525 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98525 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98530 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98535 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98533 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98537 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98532 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98526 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98525 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98526 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98523 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98526 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98528 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98534 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98536 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98531 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98866 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98869 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98870 milliseconds Feb 24 21:42:47 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [121209446] not found"} Feb 24 21:42:47 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/121209446 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [121209446] not found"} Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98872 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98873 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98875 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98884 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98877 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98883 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98868 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98869 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98869 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98892 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98898 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98904 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98903 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98903 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 98917 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 99295 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 99297 milliseconds Feb 24 21:42:47 volumio volumio[4015]: info: explodeTIDALUri took 99295 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99291 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99297 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99295 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99298 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99267 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99266 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99267 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99271 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99274 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99273 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99275 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99276 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99280 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99284 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99285 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99334 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99334 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99331 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99819 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [6938557] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/6938557 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [6938557] not found"} Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99815 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99815 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99823 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99818 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99816 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99824 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99801 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99806 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99811 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [231816603] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/231816603 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [231816603] not found"} Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99812 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99815 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99817 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99822 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99828 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99826 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [69439394] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/69439394 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [69439394] not found"} Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99826 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99830 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99834 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99837 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99834 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99838 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99841 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99848 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99848 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99849 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99851 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99855 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99862 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99863 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99931 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99944 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99943 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99947 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99947 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 99950 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 21:42:48 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 21:42:48 volumio volumio[4015]: info: Discovery: Getting this device information Feb 24 21:42:48 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:48 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100101 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809280] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809280 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809280] not found"} Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100105 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100114 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809285] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809285 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809285] not found"} Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100140 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809282] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809282 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809282] not found"} Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100172 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100195 milliseconds Feb 24 21:42:48 volumio volumio[4015]: info: explodeTIDALUri took 100195 milliseconds Feb 24 21:42:48 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809284] not found"} Feb 24 21:42:48 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/199809284 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [199809284] not found"} Feb 24 21:42:49 volumio volumio[4015]: info: explodeTIDALUri took 100236 milliseconds Feb 24 21:42:49 volumio volumio[4015]: info: VolumeController::SetAlsaVolume- Feb 24 21:42:49 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:49 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:49 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103492 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103574 milliseconds Feb 24 21:42:52 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [223967056] not found"} Feb 24 21:42:52 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/223967056 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [223967056] not found"} Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103746 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103752 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103766 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103766 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103781 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103772 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103773 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103802 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103804 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103805 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103810 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103812 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103819 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103818 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103825 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103825 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103830 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103839 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103841 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103844 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103850 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103854 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103855 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103858 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103865 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103868 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103868 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103868 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103868 milliseconds Feb 24 21:42:52 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [227197321] not found"} Feb 24 21:42:52 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/227197321 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [227197321] not found"} Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103870 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103876 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103921 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103927 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103919 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103920 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103922 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103979 milliseconds Feb 24 21:42:52 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [221962040] not found"} Feb 24 21:42:52 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/221962040 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [221962040] not found"} Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103975 milliseconds Feb 24 21:42:52 volumio volumio[4015]: info: explodeTIDALUri took 103978 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 104198 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 104203 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105103 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105108 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105112 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105112 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105098 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105100 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105102 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105102 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105099 milliseconds Feb 24 21:42:53 volumio volumio[4015]: info: explodeTIDALUri took 105100 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105103 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105108 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105111 milliseconds Feb 24 21:42:54 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [40516492] not found"} Feb 24 21:42:54 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/40516492 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [40516492] not found"} Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105109 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105107 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105107 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105111 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105114 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105114 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105117 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105122 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105126 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105126 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105125 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105128 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105132 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105135 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105135 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105135 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105139 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105148 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105149 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105153 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105159 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105163 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105165 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105166 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105172 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105176 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105177 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105100 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105106 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105112 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105112 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105114 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105116 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105270 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105271 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105276 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105285 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105283 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105282 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105284 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105290 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105295 milliseconds Feb 24 21:42:54 volumio volumio[4015]: info: explodeTIDALUri took 105626 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106604 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106607 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106606 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106608 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106613 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106617 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106616 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106616 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106618 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106621 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106624 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106766 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106767 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106768 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106776 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106776 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106775 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106775 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106781 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106786 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106788 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106779 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106791 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106797 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106801 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106801 milliseconds Feb 24 21:42:55 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [99647471] not found"} Feb 24 21:42:55 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/99647471 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [99647471] not found"} Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106800 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106794 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106801 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106818 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106800 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106805 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106799 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106818 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106824 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106819 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106822 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106823 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106827 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106833 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106832 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106845 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106837 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106841 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106842 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106850 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106853 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106854 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106864 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106868 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106868 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106873 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106875 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106879 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106882 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106882 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106886 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106890 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106889 milliseconds Feb 24 21:42:55 volumio volumio[4015]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [68667816] not found"} Feb 24 21:42:55 volumio volumio[4015]: error: Commandrouter: Cannot explode uri tidal://song/68667816 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [68667816] not found"} Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106890 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106893 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106902 milliseconds Feb 24 21:42:55 volumio volumio[4015]: info: explodeTIDALUri took 106903 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106903 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106905 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106909 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106914 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106917 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106919 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106920 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106922 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106925 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106929 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106928 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106926 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106933 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106935 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106936 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106939 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106939 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106944 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106948 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106951 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106948 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106947 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106949 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106952 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106952 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106946 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106951 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106951 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 106966 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107522 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107531 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107533 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107536 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107539 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107544 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107547 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107548 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107550 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107553 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107558 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107558 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107558 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107559 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107562 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107567 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107567 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107569 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107571 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107576 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107583 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107583 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107587 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107593 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107597 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107597 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107598 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107602 milliseconds Feb 24 21:42:56 volumio volumio[4015]: info: explodeTIDALUri took 107607 milliseconds Feb 24 21:42:57 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 21:42:57 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 24 21:42:57 volumio volumio[4015]: info: explodeTIDALUri took 108273 milliseconds Feb 24 21:42:57 volumio volumio[4015]: info: explodeTIDALUri took 108289 milliseconds Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 24 21:42:57 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Feb 24 21:42:58 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Feb 24 21:42:58 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Feb 24 21:42:58 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Feb 24 21:42:58 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Feb 24 21:42:58 volumio volumio[4015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Feb 24 21:42:58 volumio volumio[4015]: info: explodeTIDALUri took 108994 milliseconds Feb 24 21:42:58 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:58 volumio volumio[4015]: info: CoreCommandRouter::volumioGetState Feb 24 21:42:58 volumio volumio[4015]: info: getStreamUrl took 89827 milliseconds Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEiczZDg5Y2Y4NDUzZDViNmJhNzFlMTY1YTg0OTNlN2M0M182MS5tcDQ/0.flac?Expires=1740433378&Signature=MO4ZAiGbXbZaiL8k5XVamLCgLrfK~wZQIwyU~9zNCNTh7qMv8FflMfZVAOFK~HV~UHjQfcLL7fSXdfeELFLiwVQ2F8DJjZUE3CBqzOayghXT0lE9P0EvCY8vnDMrVXkRqQmv4U1qMBWpbBfHDtikbaXq68XJCvE8KXSkhfKbw0FXTbhzNodEkwlN6iY0qFuoWffwCgqImkgo~TSsSTzQlctWmM4emxS1qi8-aLJROsdpzN-Bu2eQ3xNURc4KDPpwbG8kRyjKtsrATgHQS~kR274UOckYkMw1uFBK2lxsQEgKdCnyDs30wy8EznVYWQDm-BS0Vm053wsnCbP8wiMLiw__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 24 21:42:58 volumio volumio[4015]: info: getStreamUrl took 53586 milliseconds Feb 24 21:42:58 volumio volumio[4015]: info: getStreamUrl took 44879 milliseconds Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEiczZDg5Y2Y4NDUzZDViNmJhNzFlMTY1YTg0OTNlN2M0M182MS5tcDQ/0.flac?Expires=1740433378&Signature=MO4ZAiGbXbZaiL8k5XVamLCgLrfK~wZQIwyU~9zNCNTh7qMv8FflMfZVAOFK~HV~UHjQfcLL7fSXdfeELFLiwVQ2F8DJjZUE3CBqzOayghXT0lE9P0EvCY8vnDMrVXkRqQmv4U1qMBWpbBfHDtikbaXq68XJCvE8KXSkhfKbw0FXTbhzNodEkwlN6iY0qFuoWffwCgqImkgo~TSsSTzQlctWmM4emxS1qi8-aLJROsdpzN-Bu2eQ3xNURc4KDPpwbG8kRyjKtsrATgHQS~kR274UOckYkMw1uFBK2lxsQEgKdCnyDs30wy8EznVYWQDm-BS0Vm053wsnCbP8wiMLiw__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidiNjViNTRjODM5MDY5ZTUxMTNlMzFkMTY3YTM3MWFiZV82MS5tcDQ/0.flac?Expires=1740433094&Signature=ZWLQeGX~UIDt6b0tq0S2ArNaU1sJ~vL~PtC3pGS04xukXeKfG9BcHrb4uBdftzFxMAKPx~jCCeHwISpF3yaooSuXAYNvAJG6QStxVRPsZP1ZY-bq99VhX9o26FjAxwyCYFjgknLnbgG2NtoDQOsv91ZS6hsHkGpkOH99lRea0db8jFZOqEoztySi6JDZEzRXnM7DgMOXYQocmsDVVI2pQU796J52A2mVodi5BUiwlYsqVSTJRBQQXP99F9h-8bmbRoN8bRhG-DVmVMeukf7NZ1yL6XNE-Zio3Pfvae2WlvHOy7O1XSMkySXGwMZKv~-RtsA7j9y1JQdVWeHhNWTkWA__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 24 21:42:58 volumio volumio[4015]: info: getStreamUrl took 53603 milliseconds Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEiczZDg5Y2Y4NDUzZDViNmJhNzFlMTY1YTg0OTNlN2M0M182MS5tcDQ/0.flac?Expires=1740433378&Signature=MO4ZAiGbXbZaiL8k5XVamLCgLrfK~wZQIwyU~9zNCNTh7qMv8FflMfZVAOFK~HV~UHjQfcLL7fSXdfeELFLiwVQ2F8DJjZUE3CBqzOayghXT0lE9P0EvCY8vnDMrVXkRqQmv4U1qMBWpbBfHDtikbaXq68XJCvE8KXSkhfKbw0FXTbhzNodEkwlN6iY0qFuoWffwCgqImkgo~TSsSTzQlctWmM4emxS1qi8-aLJROsdpzN-Bu2eQ3xNURc4KDPpwbG8kRyjKtsrATgHQS~kR274UOckYkMw1uFBK2lxsQEgKdCnyDs30wy8EznVYWQDm-BS0Vm053wsnCbP8wiMLiw__&Key-Pair-Id=K14LZCZ9QUI4JL Feb 24 21:42:58 volumio volumio[4015]: info: explodeTIDALUri took 109196 milliseconds Feb 24 21:42:58 volumio volumio[4015]: info: CoreCommandRouter::volumioPushQueue Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Response: 200, length: 27314331 Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Response: 200, length: 27314331 Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Response: 200, length: 49061601 Feb 24 21:42:58 volumio volumio[4015]: STREAMING PROXY: Response: 200, length: 27314331 Feb 24 21:42:58 volumio volumio[4015]: info: CorePlayQueue::saveQueue Feb 24 21:42:58 volumio volumio[4015]: info: CoreStateMachine::updateTrackBlock Feb 24 21:42:58 volumio volumio[4015]: info: CorePlayQueue::getTrackBlock Feb 24 21:42:58 volumio volumio[4015]: info: Feb 24 21:42:58 volumio volumio[4015]: ---------------------------- MPD announces state update: player Feb 24 21:42:58 volumio volumio[4015]: info: ControllerMpd::getState Feb 24 21:42:58 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand status Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: Feb 24 21:42:59 volumio volumio[4015]: ---------------------------- MPD announces state update: player Feb 24 21:42:59 volumio volumio[4015]: info: ControllerMpd::getState Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand status Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 21:42:59 volumio volumio[4015]: info: sendMpdCommand status took 601 milliseconds Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::parseState Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 21:42:59 volumio volumio[4015]: info: sendMpdCommand status took 255 milliseconds Feb 24 21:42:59 volumio volumio[4015]: info: sendMpdCommand clearerror took 246 milliseconds Feb 24 21:42:59 volumio volumio[4015]: info: sendMpdCommand playlistinfo took 245 milliseconds Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::parseState Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 21:42:59 volumio volumio[4015]: verbose: ControllerMpd::parseTrackInfo Feb 24 21:42:59 volumio volumio[4015]: info: ControllerMpd::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:42:59 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:42:59 volumio volumio[4015]: info: ------------------------------ 892ms Feb 24 21:43:00 volumio volumio[4015]: info: sendMpdCommand clearerror took 567 milliseconds Feb 24 21:43:00 volumio volumio[4015]: info: sendMpdCommand playlistinfo took 565 milliseconds Feb 24 21:43:00 volumio volumio[4015]: verbose: ControllerMpd::parseTrackInfo Feb 24 21:43:00 volumio volumio[4015]: info: ControllerMpd::pushState Feb 24 21:43:00 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:43:00 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:00 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:00 volumio volumio[4015]: info: ------------------------------ 879ms Feb 24 21:43:00 volumio volumio[4015]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 21:43:00 volumio volumio[4015]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 21:43:02 volumio volumio[4015]: info: Executing endpoint metavolumio Feb 24 21:43:02 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 21:43:05 volumio volumio[4015]: info: Enabling plugin fusiondsp Feb 24 21:43:05 volumio volumio[4015]: info: Loading plugin "fusiondsp"... Feb 24 21:43:05 volumio shairport-sync[5827]: {"time":1740429751850,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 21:43:05 volumio shairport-sync[5827]: Dload Upload Total Spent Left Speed Feb 24 21:43:06 volumio volumio[4015]: info: Preparing to generate the ALSA configuration file Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::servicePushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:06 volumio volumio[4015]: info: Feb 24 21:43:06 volumio volumio[4015]: ---------------------------- Client requests Stop Airplay Playback Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Feb 24 21:43:06 volumio volumio[4015]: info: Airplay playback stop Feb 24 21:43:06 volumio volumio[4015]: info: Airplay Playback Stopped, clearing state Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::resetVolumioState Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::getcurrentVolume Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 21:43:06 volumio shairport-sync[5827]: [237B blob data] Feb 24 21:43:06 volumio volumio[4015]: info: CoreStateMachine::pushState Feb 24 21:43:06 volumio volumio[4015]: info: CoreCommandRouter::volumioPushState Feb 24 21:43:06 volumio volumio[4015]: info: Asound.conf file unchanged, so no further update is needed Feb 24 21:43:06 volumio volumio[4015]: info: Output device has changed, restarting MPD Feb 24 21:43:06 volumio sudo[6389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:43:06 volumio sudo[6389]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:06 volumio sudo[6389]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:07 volumio volumio[4015]: info: Output device has changed, restarting Shairport Sync Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:43:07 volumio sudo[6393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:43:07 volumio sudo[6393]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:07 volumio systemd[1]: Stopping Music Player Daemon... Feb 24 21:43:07 volumio volumio[4015]: STREAMING PROXY: Client dropped request, destroying Feb 24 21:43:07 volumio systemd[1]: mpd.service: Succeeded. Feb 24 21:43:07 volumio systemd[1]: Stopped Music Player Daemon. Feb 24 21:43:07 volumio volumio[4015]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:43:07 volumio volumio[4015]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio systemd[1]: Starting Music Player Daemon... Feb 24 21:43:07 volumio vtcs[5844]: [2025-02-24 21:43:07.312] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Feb 24 21:43:07 volumio vtcs[5844]: [2025-02-24 21:43:07.314] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe Feb 24 21:43:07 volumio vtcs[5844]: [2025-02-24 21:43:07.314] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Feb 24 21:43:07 volumio vtcs[5844]: [2025-02-24 21:43:07.314] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 24 21:43:07 volumio sudo[6398]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 21:43:07 volumio sudo[6398]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:07 volumio sudo[6398]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:07 volumio volumio[4015]: info: PLUGIN START: fusiondsp Feb 24 21:43:07 volumio volumio[4015]: info: Loading i18n strings for locale en Feb 24 21:43:07 volumio sudo[6404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:43:07 volumio sudo[6404]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: FusionDsp - mixtype--------------------- None Feb 24 21:43:07 volumio volumio[4015]: info: Preparing to generate the ALSA configuration file Feb 24 21:43:07 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Feb 24 21:43:07 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 24 21:43:07 volumio systemd[1]: vtcs.service: Succeeded. Feb 24 21:43:07 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Feb 24 21:43:07 volumio volumio[4015]: info: Done. Feb 24 21:43:07 volumio sudo[6404]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:07 volumio sudo[6408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:43:07 volumio sudo[6408]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:07 volumio sudo[6408]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:07 volumio volumio[4015]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 24 21:43:07 volumio volumio[4015]: info: Reading ALSA contributions from plugins. Feb 24 21:43:07 volumio volumio[4015]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 21:43:07 volumio volumio[4015]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 24 21:43:07 volumio volumio[4015]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 21:43:07 volumio volumio[4015]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 21:43:07 volumio volumio[4015]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 21:43:07 volumio volumio[4015]: info: MPD Permissions set Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:43:07 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:08 volumio volumio[4015]: info: Starting Shairport Sync Feb 24 21:43:08 volumio volumio[4015]: info: Asound.conf file written Feb 24 21:43:08 volumio sudo[6419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 21:43:08 volumio sudo[6419]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 21:43:08 volumio shairport-sync[5827]: {"time":1740429786667,"response":"stopAirplayPlayback Success"} Feb 24 21:43:08 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 24 21:43:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 21:43:08 volumio sudo[6423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 21:43:08 volumio sudo[6423]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 21:43:08 volumio sudo[6423]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:08 volumio sudo[6419]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:08 volumio volumio[4015]: info: Output device has changed, restarting MPD Feb 24 21:43:09 volumio sudo[6431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 21:43:09 volumio sudo[6431]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:09 volumio sudo[6431]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:09 volumio mpd[6403]: Feb 24 21:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 21:43:09 volumio systemd[1]: Started Music Player Daemon. Feb 24 21:43:09 volumio sudo[6393]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:09 volumio volumio[4015]: info: Output device has changed, restarting Shairport Sync Feb 24 21:43:09 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:09 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 21:43:09 volumio sudo[6436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 21:43:09 volumio sudo[6436]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:09 volumio systemd[1]: Stopping Music Player Daemon... Feb 24 21:43:09 volumio systemd[1]: mpd.service: Succeeded. Feb 24 21:43:09 volumio systemd[1]: Stopped Music Player Daemon. Feb 24 21:43:09 volumio volumio[4015]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 21:43:09 volumio volumio[4015]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 24 21:43:09 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:09 volumio systemd[1]: Starting Music Player Daemon... Feb 24 21:43:09 volumio sudo[6441]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 21:43:09 volumio sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:09 volumio sudo[6441]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:09 volumio sudo[6447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:43:09 volumio sudo[6447]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:09 volumio sudo[6447]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:09 volumio sudo[6450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 24 21:43:09 volumio sudo[6450]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:09 volumio sudo[6450]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:09 volumio volumio[4015]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Feb 24 21:43:09 volumio volumio[4015]: info: camilladsp service started and running in background, instance 1 Feb 24 21:43:09 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 21:43:10 volumio volumio[4015]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 24 21:43:10 volumio volumio[4015]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Feb 24 21:43:10 volumio volumio[4015]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 24 21:43:10 volumio volumio[4015]: info: FusionDsp loaded Feb 24 21:43:10 volumio volumio[4015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 21:43:10 volumio sudo[6464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 24 21:43:10 volumio sudo[6464]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 21:43:10 volumio volumio[4015]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 24 21:43:10 volumio volumio[4015]: info: Adding Signal Path Element [object Object] Feb 24 21:43:10 volumio volumio[4015]: info: Adding fusiondspeq DSP Signal Path Element Feb 24 21:43:10 volumio volumio[4015]: info: FusionDsp - ---- installed callbackRead Feb 24 21:43:10 volumio volumio[4015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 21:43:10 volumio sudo[6464]: pam_unix(sudo:session): session closed for user root Feb 24 21:43:10 volumio volumio[4015]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Feb 24 21:43:10 volumio volumio[4015]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Feb 24 21:43:10 volumio volumio[4015]: at onErrorNT (internal/child_process.js:465:16) Feb 24 21:43:10 volumio volumio[4015]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 24 21:43:10 volumio volumio[4015]: at runNextTicks (internal/process/task_queues.js:62:3) Feb 24 21:43:10 volumio volumio[4015]: at listOnTimeout (internal/timers.js:523:9) Feb 24 21:43:10 volumio volumio[4015]: at processTimers (internal/timers.js:497:7) { Feb 24 21:43:10 volumio volumio[4015]: errno: -2, Feb 24 21:43:10 volumio volumio[4015]: code: 'ENOENT', Feb 24 21:43:10 volumio volumio[4015]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Feb 24 21:43:10 volumio volumio[4015]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Feb 24 21:43:10 volumio volumio[4015]: spawnargs: [ Feb 24 21:43:10 volumio volumio[4015]: '-p', Feb 24 21:43:10 volumio volumio[4015]: 9876, Feb 24 21:43:10 volumio volumio[4015]: '-o', Feb 24 21:43:10 volumio volumio[4015]: '/tmp/camilladsp.log', Feb 24 21:43:10 volumio volumio[4015]: '-l', Feb 24 21:43:10 volumio volumio[4015]: 'warn', Feb 24 21:43:10 volumio volumio[4015]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Feb 24 21:43:10 volumio volumio[4015]: ] Feb 24 21:43:10 volumio volumio[4015]: } Feb 24 21:43:10 volumio volumio[4015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 21:43:11 volumio sudo[6489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 21:42 Feb 24 21:43:11 volumio sudo[6489]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"