Dec 13 03:38:01 volumio volumio[1097]: info: Check plugin dependencies Dec 13 03:38:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 03:38:01 volumio volumio[1097]: info: An error occurred installing the plugin. Rolling back config Dec 13 03:38:01 volumio volumio[1097]: info: Plugin folders cleanup Dec 13 03:38:01 volumio volumio[1097]: info: Scanning into folder /volumio/app/plugins/ Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category audio_interface Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category miscellanea Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category music_service Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category plugins.json Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category system_controller Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category user_interface Dec 13 03:38:01 volumio volumio[1097]: info: Scanning into folder /data/plugins/ Dec 13 03:38:01 volumio volumio[1097]: info: Scanning category music_service Dec 13 03:38:01 volumio volumio[1097]: info: Plugin folders cleanup completed Dec 13 03:38:01 volumio volumio[1097]: info: Error: Error Dec 13 03:38:01 volumio volumio[1097]: info: Folder /tmp/plugins removed Dec 13 03:38:01 volumio volumio[1097]: info: Folder /tmp/downloaded_plugin.zip removed Dec 13 03:38:01 volumio volumio[1097]: info: Folder /tmp/downloaded_plugin.zip removed Dec 13 03:38:02 volumio volumio[1097]: info: Folder /data/temp removed Dec 13 03:38:07 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 13 03:38:11 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 13 03:38:16 volumio volumio[1097]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/radio_paradise/volumio/bookworm/armhf Dec 13 03:38:18 volumio volumio[1097]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/radio_paradise/volumio/bookworm/armhf Dec 13 03:38:18 volumio volumio[1097]: info: Folder /tmp/plugins removed Dec 13 03:38:18 volumio volumio[1097]: info: Check plugin dependencies Dec 13 03:38:18 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 03:38:18 volumio volumio[1097]: info: An error occurred installing the plugin. Rolling back config Dec 13 03:38:18 volumio volumio[1097]: info: Plugin folders cleanup Dec 13 03:38:18 volumio volumio[1097]: info: Scanning into folder /volumio/app/plugins/ Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category audio_interface Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category miscellanea Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category music_service Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category plugins.json Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category system_controller Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category user_interface Dec 13 03:38:18 volumio volumio[1097]: info: Scanning into folder /data/plugins/ Dec 13 03:38:18 volumio volumio[1097]: info: Scanning category music_service Dec 13 03:38:18 volumio volumio[1097]: info: Plugin folders cleanup completed Dec 13 03:38:18 volumio volumio[1097]: info: Error: Error Dec 13 03:38:18 volumio volumio[1097]: info: Folder /tmp/plugins removed Dec 13 03:38:18 volumio volumio[1097]: info: Folder /tmp/downloaded_plugin.zip removed Dec 13 03:38:18 volumio volumio[1097]: info: Folder /tmp/downloaded_plugin.zip removed Dec 13 03:38:19 volumio volumio[1097]: info: Folder /data/temp removed Dec 13 03:38:26 volumio volumio[1097]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/rad357pl/volumio/bookworm/armhf Dec 13 03:38:27 volumio volumio[1097]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/rad357pl/volumio/bookworm/armhf Dec 13 03:38:27 volumio volumio[1097]: info: Folder /tmp/plugins removed Dec 13 03:38:28 volumio volumio[1097]: info: Check plugin dependencies Dec 13 03:38:28 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 03:38:28 volumio volumio[1097]: info: Checking if plugin already exists Dec 13 03:38:28 volumio volumio[1097]: info: Rename folder Dec 13 03:38:28 volumio volumio[1097]: info: Folder /tmp/downloaded_plugin.zip removed Dec 13 03:38:28 volumio volumio[1097]: info: Move to category Dec 13 03:38:28 volumio volumio[1097]: info: Checking if install.sh is present Dec 13 03:38:28 volumio volumio[1097]: info: Executing install.sh Dec 13 03:38:28 volumio sudo[12251]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/rad357pl/install.sh Dec 13 03:38:28 volumio sudo[12251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 03:38:28 volumio sudo[12251]: pam_unix(sudo:session): session closed for user root Dec 13 03:38:28 volumio volumio[1097]: info: Install script completed Dec 13 03:38:28 volumio volumio[1097]: info: Adding reference to registry Dec 13 03:38:28 volumio volumio[1097]: info: Done installing plugin. Dec 13 03:38:28 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 03:38:28 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 13 03:38:28 volumio volumio[1097]: info: Installing radio 357 Dependencies Dec 13 03:38:28 volumio volumio[1097]: Plugin install end detected on script Dec 13 03:38:28 volumio volumio[1097]: info: Folder /tmp/plugins removed Dec 13 03:38:28 volumio volumio[1097]: info: Folder /tmp/downloaded_plugin.zip removed Dec 13 03:38:28 volumio volumio[1097]: info: Folder /data/temp removed Dec 13 03:38:30 volumio volumio[1097]: info: Enabling plugin rad357pl Dec 13 03:38:30 volumio volumio[1097]: info: Loading plugin "rad357pl"... Dec 13 03:38:31 volumio volumio[1097]: info: [1765564711146] [rad357pl] API delay: 5 Dec 13 03:38:31 volumio volumio[1097]: info: PLUGIN START: rad357pl Dec 13 03:38:31 volumio volumio[1097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 03:38:31 volumio volumio[1097]: info: [1765564711159] CoreMusicLibrary::Adding element RADIO 357 Dec 13 03:38:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 03:38:31 volumio volumio[1097]: Cannot find translation for source YouTube2 Dec 13 03:38:31 volumio volumio[1097]: Cannot find translation for source YouTube Music Dec 13 03:38:31 volumio volumio[1097]: Cannot find translation for source 80s80s Radio Dec 13 03:38:31 volumio volumio[1097]: Cannot find translation for source RADIO 357 Dec 13 03:38:31 volumio volumio[1097]: info: Done. Dec 13 03:38:32 volumio volumio[1097]: info: Enabling plugin rad357pl Dec 13 03:38:32 volumio volumio[1097]: info: Loading plugin "rad357pl"... Dec 13 03:38:32 volumio volumio[1097]: info: Applying required configuration parameters for plugin rad357pl Dec 13 03:38:32 volumio volumio[1097]: info: [1765564712372] [rad357pl] API delay: 5 Dec 13 03:38:32 volumio volumio[1097]: info: PLUGIN START: rad357pl Dec 13 03:38:32 volumio volumio[1097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 03:38:32 volumio volumio[1097]: info: [1765564712376] CoreMusicLibrary::Adding element RADIO 357 Dec 13 03:38:32 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 03:38:32 volumio volumio[1097]: Cannot find translation for source YouTube2 Dec 13 03:38:32 volumio volumio[1097]: Cannot find translation for source YouTube Music Dec 13 03:38:32 volumio volumio[1097]: Cannot find translation for source 80s80s Radio Dec 13 03:38:32 volumio volumio[1097]: Cannot find translation for source RADIO 357 Dec 13 03:38:32 volumio volumio[1097]: info: Done. Dec 13 03:38:33 volumio volumio[1097]: info: Enabling plugin rad357pl Dec 13 03:38:33 volumio volumio[1097]: info: Loading plugin "rad357pl"... Dec 13 03:38:33 volumio volumio[1097]: info: Applying required configuration parameters for plugin rad357pl Dec 13 03:38:33 volumio volumio[1097]: info: [1765564713794] [rad357pl] API delay: 5 Dec 13 03:38:33 volumio volumio[1097]: info: PLUGIN START: rad357pl Dec 13 03:38:33 volumio volumio[1097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 03:38:33 volumio volumio[1097]: info: [1765564713800] CoreMusicLibrary::Adding element RADIO 357 Dec 13 03:38:33 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 03:38:33 volumio volumio[1097]: Cannot find translation for source YouTube2 Dec 13 03:38:33 volumio volumio[1097]: Cannot find translation for source YouTube Music Dec 13 03:38:33 volumio volumio[1097]: Cannot find translation for source 80s80s Radio Dec 13 03:38:33 volumio volumio[1097]: Cannot find translation for source RADIO 357 Dec 13 03:38:33 volumio volumio[1097]: info: Done. Dec 13 03:38:34 volumio volumio[1097]: info: Enabling plugin rad357pl Dec 13 03:38:34 volumio volumio[1097]: info: Loading plugin "rad357pl"... Dec 13 03:38:34 volumio volumio[1097]: info: Applying required configuration parameters for plugin rad357pl Dec 13 03:38:34 volumio volumio[1097]: info: [1765564714833] [rad357pl] API delay: 5 Dec 13 03:38:34 volumio volumio[1097]: info: PLUGIN START: rad357pl Dec 13 03:38:34 volumio volumio[1097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 03:38:34 volumio volumio[1097]: info: [1765564714839] CoreMusicLibrary::Adding element RADIO 357 Dec 13 03:38:34 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 03:38:34 volumio volumio[1097]: Cannot find translation for source YouTube2 Dec 13 03:38:34 volumio volumio[1097]: Cannot find translation for source YouTube Music Dec 13 03:38:34 volumio volumio[1097]: Cannot find translation for source 80s80s Radio Dec 13 03:38:34 volumio volumio[1097]: Cannot find translation for source RADIO 357 Dec 13 03:38:34 volumio volumio[1097]: info: Done. Dec 13 03:38:38 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 13 03:38:38 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:41 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Dec 13 03:38:41 volumio volumio[1097]: info: [1765564721966] [rad357pl] handleBrowseUri curUri: rad357pl Dec 13 03:38:41 volumio volumio[1097]: info: [1765564721967] [rad357pl] getRadioContent url: rad357pl Dec 13 03:38:42 volumio volumio[1097]: info: Preload queue cleared Dec 13 03:38:43 volumio volumio[1097]: info: Preload queue cleared Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::ClearQueue Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::stop Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::stPlaybackTimer Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::updateTrackBlock Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::getTrackBlock Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::serviceStop Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::serviceStop Dec 13 03:38:43 volumio volumio[1097]: error: WARNING: No stop method for service motherearthradio Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::clearPlayQueue Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::saveQueue Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::volumioPushQueue Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::addQueueItems Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::addQueueItems Dec 13 03:38:43 volumio volumio[1097]: info: Preload queue cleared Dec 13 03:38:43 volumio volumio[1097]: info: Adding Item to queue: webrns/0 Dec 13 03:38:43 volumio volumio[1097]: info: Exploding uri webrns/0 in service rad357pl Dec 13 03:38:43 volumio volumio[1097]: info: [1765564723639] [rad357pl] explodeUri: webrns/0 Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::volumioPushQueue Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::saveQueue Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::updateTrackBlock Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::getTrackBlock Dec 13 03:38:43 volumio volumio[1097]: info: CoreCommandRouter::volumioPlay Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::play index 0 Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::stop Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::play index undefined Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::startPlaybackTimer Dec 13 03:38:43 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:43 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 03:38:43 volumio volumio[1097]: info: sendMpdCommand stop took 4 milliseconds Dec 13 03:38:43 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 03:38:43 volumio volumio[1097]: info: sendMpdCommand clear took 1 milliseconds Dec 13 03:38:43 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Dec 13 03:38:43 volumio volumio[1097]: info: Dec 13 03:38:43 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:43 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:43 volumio volumio[1097]: info: Dec 13 03:38:43 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:43 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:43 volumio volumio[1097]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 2 milliseconds Dec 13 03:38:43 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand play Dec 13 03:38:43 volumio volumio[1097]: info: Dec 13 03:38:43 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:43 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:43 volumio volumio[1097]: info: Dec 13 03:38:43 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:43 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:43 volumio volumio[1097]: info: ------------------------------ 12ms Dec 13 03:38:43 volumio volumio[1097]: info: ------------------------------ 11ms Dec 13 03:38:43 volumio volumio[1097]: info: sendMpdCommand play took 5 milliseconds Dec 13 03:38:43 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 13 03:38:43 volumio volumio[1097]: info: ------------------------------ 8ms Dec 13 03:38:43 volumio volumio[1097]: info: ------------------------------ 6ms Dec 13 03:38:43 volumio volumio[1097]: info: Dec 13 03:38:43 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:43 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:43 volumio volumio[1097]: info: Dec 13 03:38:43 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:43 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:43 volumio volumio[1097]: info: ------------------------------ 6ms Dec 13 03:38:43 volumio volumio[1097]: info: ------------------------------ 5ms Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 6 milliseconds Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 16 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 14 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 13 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus stop Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 39ms Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:47 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:47 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 33 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 31 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 12ms Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 95ms Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 94ms Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 96ms Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 13 03:38:47 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 13 03:38:47 volumio volumio[1097]: info: Dec 13 03:38:47 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::getState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 67 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 66ms Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 62 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 10ms Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand status took 7 milliseconds Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 134ms Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 131ms Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 13 03:38:47 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ControllerMpd::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 13 03:38:47 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 13 03:38:47 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"David Byrne - T Shirt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 13 03:38:47 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 13 03:38:47 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 13 03:38:47 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 167ms Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 164ms Dec 13 03:38:47 volumio volumio[1097]: info: ------------------------------ 107ms Dec 13 03:39:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 13 03:39:01 volumio volumio[1097]: info: Preload queue cleared Dec 13 03:39:06 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 13 03:39:06 volumio volumio[1097]: info: READ Dec 13 03:39:06 volumio volumio[1097]: Use XMLElement.setAttribute instead Dec 13 03:39:06 volumio volumio[1097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 03:39:06 volumio volumio[1097]: TypeError: children[i].attr(...).value is not a function Dec 13 03:39:06 volumio volumio[1097]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 Dec 13 03:39:06 volumio volumio[1097]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 13 03:39:06 volumio volumio[1097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 03:39:08 volumio sudo[12362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 03:38' Dec 13 03:39:08 volumio sudo[12362]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"