Jun 12 22:16:01 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:16:01 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:16:01 volumio volumio[1059]: info: Discovery: Getting this device information Jun 12 22:16:01 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 12 22:16:01 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:01 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:16:07 volumio volumio[1059]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 22:16:07 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 22:16:07 volumio volumio[1059]: info: CoreCommandRouter::volumioGetQueue Jun 12 22:16:07 volumio volumio[1059]: info: CoreStateMachine::getQueue Jun 12 22:16:07 volumio volumio[1059]: info: CorePlayQueue::getQueue Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:16:14 volumio volumio[1059]: info: Retrieving Cloud Streaming UI Jun 12 22:16:14 volumio volumio[1059]: info: Getting Tidal Cloud Configuration Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:16:14 volumio volumio[1059]: info: Getting Qobuz Cloud Configuration Jun 12 22:16:14 volumio volumio[1059]: info: Asking plugin for UI Config Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:16:14 volumio volumio[1059]: info: Getting Spotify Cloud Configuration Jun 12 22:16:14 volumio volumio[1059]: info: Asking plugin for UI Config Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:16:14 volumio volumio[1059]: info: Saving Spotify Acccount Jun 12 22:16:14 volumio volumio[1059]: info: Got Tidal Cloud Configuration Jun 12 22:16:14 volumio volumio[1059]: info: Got it Jun 12 22:16:14 volumio volumio[1059]: info: Got it Jun 12 22:16:14 volumio volumio[1059]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 22:16:14 volumio volumio[1059]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:14 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 12 22:16:16 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand update Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:16 volumio volumio[1059]: info: Jun 12 22:16:16 volumio volumio[1059]: ---------------------------- MPD announces state update: update Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand update took 4 milliseconds Jun 12 22:16:16 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:16 volumio volumio[1059]: info: Command Router : Notfying DB Updatetrue Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:16 volumio volumio[1059]: info: Jun 12 22:16:16 volumio volumio[1059]: ---------------------------- MPD announces state update: update Jun 12 22:16:16 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:16 volumio volumio[1059]: info: Jun 12 22:16:16 volumio volumio[1059]: ---------------------------- MPD announces state update: update Jun 12 22:16:16 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand status took 109 milliseconds Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand status took 14 milliseconds Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand status took 11 milliseconds Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand status took 10 milliseconds Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand status took 7 milliseconds Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:16 volumio volumio[1059]: info: Command Router : Notfying DB Updatetrue Jun 12 22:16:16 volumio volumio[1059]: info: CoreCommandRouter::Close All Modals sent Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:16 volumio volumio[1059]: info: Command Router : Notfying DB Updatetrue Jun 12 22:16:16 volumio volumio[1059]: info: CoreCommandRouter::Close All Modals sent Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:16 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:16 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 104 milliseconds Jun 12 22:16:17 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 70 milliseconds Jun 12 22:16:17 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 12 22:16:17 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:17 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:17 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:17 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:17 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: No code Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:17 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: No code Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:17 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: No code Jun 12 22:16:17 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:17 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:17 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:17 volumio volumio[1059]: info: ------------------------------ 366ms Jun 12 22:16:17 volumio volumio[1059]: info: ------------------------------ 269ms Jun 12 22:16:17 volumio volumio[1059]: info: ------------------------------ 266ms Jun 12 22:16:18 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:22 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:25 volumio volumio[1059]: info: MPD Database updated - AlbumList cache refreshed Jun 12 22:16:25 volumio volumio[1059]: info: Jun 12 22:16:25 volumio volumio[1059]: ---------------------------- MPD announces state update: database Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: Jun 12 22:16:25 volumio volumio[1059]: ---------------------------- MPD announces state update: update Jun 12 22:16:25 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: MPD Database updated - AlbumList cache refreshed Jun 12 22:16:25 volumio volumio[1059]: info: Jun 12 22:16:25 volumio volumio[1059]: ---------------------------- MPD announces state update: database Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: Jun 12 22:16:25 volumio volumio[1059]: ---------------------------- MPD announces state update: update Jun 12 22:16:25 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: MPD Database updated - AlbumList cache refreshed Jun 12 22:16:25 volumio volumio[1059]: info: Jun 12 22:16:25 volumio volumio[1059]: ---------------------------- MPD announces state update: database Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: Jun 12 22:16:25 volumio volumio[1059]: ---------------------------- MPD announces state update: update Jun 12 22:16:25 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:25 volumio volumio[1059]: info: ControllerMpd::getState Jun 12 22:16:25 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Jun 12 22:16:26 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:27 volumio volumio[1059]: info: sendMpdCommand status took 1752 milliseconds Jun 12 22:16:27 volumio volumio[1059]: info: sendMpdCommand status took 1751 milliseconds Jun 12 22:16:27 volumio volumio[1059]: info: sendMpdCommand status took 1750 milliseconds Jun 12 22:16:27 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:27 volumio volumio[1059]: info: Command Router : Notfying DB Updatefalse Jun 12 22:16:27 volumio volumio[1059]: info: CoreCommandRouter::Close All Modals sent Jun 12 22:16:27 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:27 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:27 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:27 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:28 volumio volumio[1059]: info: sendMpdCommand status took 2875 milliseconds Jun 12 22:16:28 volumio volumio[1059]: info: sendMpdCommand status took 2875 milliseconds Jun 12 22:16:28 volumio volumio[1059]: info: sendMpdCommand status took 2875 milliseconds Jun 12 22:16:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:28 volumio volumio[1059]: info: Command Router : Notfying DB Updatefalse Jun 12 22:16:28 volumio volumio[1059]: info: CoreCommandRouter::Close All Modals sent Jun 12 22:16:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:28 volumio volumio[1059]: info: Enabling MyMusic plugin smart_inputs Jun 12 22:16:28 volumio volumio[1059]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service smart_inputs Jun 12 22:16:28 volumio volumio[1059]: info: [MyVolumio PluginManager] Plugin music_service smart_inputs not in user plan, not enabling Jun 12 22:16:28 volumio volumio[1059]: info: Error: Plugin music_service smart_inputs not in user plan, not enabling Jun 12 22:16:28 volumio volumio[1059]: error: Could not Enable MyMusic Plugin: Error Jun 12 22:16:29 volumio volumio[1059]: info: sendMpdCommand status took 4000 milliseconds Jun 12 22:16:29 volumio volumio[1059]: info: sendMpdCommand status took 4000 milliseconds Jun 12 22:16:29 volumio volumio[1059]: info: sendMpdCommand status took 3998 milliseconds Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:29 volumio volumio[1059]: info: Command Router : Notfying DB Updatefalse Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::Close All Modals sent Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::parseState Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 22:16:29 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2219 milliseconds Jun 12 22:16:29 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2218 milliseconds Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:29 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:29 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:29 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:29 volumio volumio[1059]: info: No code Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:29 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:29 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:29 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: No code Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: ------------------------------ 4114ms Jun 12 22:16:30 volumio volumio[1059]: info: ------------------------------ 4114ms Jun 12 22:16:30 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 1210 milliseconds Jun 12 22:16:30 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 1210 milliseconds Jun 12 22:16:30 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 88 milliseconds Jun 12 22:16:30 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 87 milliseconds Jun 12 22:16:30 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:30 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:30 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:30 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Jun 12 22:16:30 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:30 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: No code Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:30 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: No code Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:30 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: No code Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: ControllerMpd::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Fine Day To Die","artist":"Bathory","album":"Blood Fire Death","uri":"USB/SD240/Bathory/1988 - Blood Fire Death/Bathory - Blood Fire Death - 02 - Fine Day to Die(1).mp3","trackType":"mp3"} Jun 12 22:16:30 volumio volumio[1059]: verbose: CURRENT POSITION 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState stateService stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: No code Jun 12 22:16:30 volumio volumio[1059]: info: CoreStateMachine::pushState Jun 12 22:16:30 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 12 22:16:30 volumio volumio[1059]: info: ------------------------------ 4258ms Jun 12 22:16:30 volumio volumio[1059]: info: ------------------------------ 4258ms Jun 12 22:16:30 volumio volumio[1059]: info: ------------------------------ 4256ms Jun 12 22:16:30 volumio volumio[1059]: info: ------------------------------ 4257ms Jun 12 22:16:30 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:31 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:16:31 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:16:31 volumio volumio[1059]: info: Discovery: Getting this device information Jun 12 22:16:31 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 12 22:16:31 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:16:31 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:16:32 volumio volumio[1059]: info: Enabling MyMusic plugin smart_inputs Jun 12 22:16:32 volumio volumio[1059]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service smart_inputs Jun 12 22:16:32 volumio volumio[1059]: info: [MyVolumio PluginManager] Plugin music_service smart_inputs not in user plan, not enabling Jun 12 22:16:32 volumio volumio[1059]: info: Error: Plugin music_service smart_inputs not in user plan, not enabling Jun 12 22:16:32 volumio volumio[1059]: error: Could not Enable MyMusic Plugin: Error Jun 12 22:16:34 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:38 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:42 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:46 volumio volumio[1059]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Jun 12 22:16:46 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Jun 12 22:16:46 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:46 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Jun 12 22:16:46 volumio volumio[1059]: info: Rebuild Album cache Jun 12 22:16:48 volumio volumio[1059]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Jun 12 22:16:48 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Jun 12 22:16:50 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:54 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 22:16:54 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 12 22:16:54 volumio dbus-daemon[595]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.112' (uid=0 pid=9227 comm="timedatectl show --property=NTPSynchronized --valu") Jun 12 22:16:54 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 12 22:16:55 volumio dbus-daemon[595]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 22:16:55 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 12 22:16:55 volumio setdatetime-helper.sh[9226]: Time is already synchronized. Jun 12 22:16:55 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 12 22:16:55 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 12 22:16:59 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 12 22:16:59 volumio volumio[1059]: info: Preload queue cleared Jun 12 22:17:01 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 22:17:01 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 22:17:01 volumio volumio[1059]: info: Discovery: Getting this device information Jun 12 22:17:01 volumio volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 12 22:17:01 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 12 22:17:01 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 22:17:04 volumio volumio[1059]: Searching plugin music_service/webradio Jun 12 22:17:04 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 12 22:17:04 volumio volumio[1059]: Use XMLElement.setAttribute instead Jun 12 22:17:04 volumio volumio[1059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 22:17:04 volumio volumio[1059]: TypeError: children[i].attr(...).value is not a function Jun 12 22:17:04 volumio volumio[1059]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 12 22:17:04 volumio volumio[1059]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 12 22:17:04 volumio volumio[1059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 22:17:05 volumio sudo[9259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-12 22:16' Jun 12 22:17:05 volumio sudo[9259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="ecd46641d85b721d58d1c7edd71dd0a358c8514c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"