Sep 13 10:55:09 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 13 10:55:09 volumio volumio[1000]: info: CURURI: playlists Sep 13 10:55:09 volumio volumio[1000]: info: Listing playlists Sep 13 10:55:09 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:09 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 13 10:55:09 volumio dbus-daemon[637]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.135' (uid=0 pid=18994 comm="timedatectl show --property=NTPSynchronized --valu") Sep 13 10:55:09 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 13 10:55:09 volumio dbus-daemon[637]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 13 10:55:09 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 13 10:55:09 volumio setdatetime-helper.sh[18993]: Time is already synchronized. Sep 13 10:55:09 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 13 10:55:09 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 13 10:55:11 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 13 10:55:11 volumio volumio[1000]: info: CURURI: playlists/ambient Sep 13 10:55:11 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:11 volumio volumio[1000]: info: Preloading song: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22HB_YGC45Ph4%22%2C%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%228gECGAI%253D%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D Sep 13 10:55:11 volumio volumio[1000]: info: Exploding uri ytmusic/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22HB_YGC45Ph4%22%2C%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%228gECGAI%253D%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D in service ytmusic Sep 13 10:55:11 volumio volumio[1000]: info: [ytmusic-browse] explodeUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22HB_YGC45Ph4%22%2C%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%228gECGAI%253D%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D Sep 13 10:55:15 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::ClearQueue Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::serviceStop Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::serviceStop Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::stop Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::clearPlayQueue Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::addQueueItems Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::addQueueItems Sep 13 10:55:15 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:15 volumio volumio[1000]: info: Adding Item to queue: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22HB_YGC45Ph4%22%2C%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%228gECGAI%253D%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D Sep 13 10:55:15 volumio volumio[1000]: info: Using cached record of: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22HB_YGC45Ph4%22%2C%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%228gECGAI%253D%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPlay Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::play index 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::play index undefined Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::startPlaybackTimer Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Transcend%22%2C%22artist%22%3A%22Solara%20Dawn%22%2C%22album%22%3A%22Connecting%20to%20Mindfulness%20with%20Ambient%20Sounds%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F8Ilh41FVfN-jfb7_xsrgrfG5S1Mol8mV_0t4SkX6Kefrk69jrv_KUQEtBklPGNdQAF0grKJcBRPqLTc%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22HB_YGC45Ph4%22%2C%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%2256B44F6D10557CC6%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDATfgYW1iaWVudA%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22videoId%22%3A%22fxhEGU_GWd0%22%7D%7D%7D%7D Sep 13 10:55:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:15 volumio volumio[1000]: info: Sep 13 10:55:15 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand stop took 30 milliseconds Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:15 volumio volumio[1000]: info: Sep 13 10:55:15 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:15 volumio volumio[1000]: info: Sep 13 10:55:15 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:55:15 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:15 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Sep 13 10:55:15 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:15 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Sep 13 10:55:15 volumio volumio[1000]: info: ------------------------------ 13ms Sep 13 10:55:15 volumio volumio[1000]: info: ------------------------------ 13ms Sep 13 10:55:15 volumio volumio[1000]: info: ------------------------------ 12ms Sep 13 10:55:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Sep 13 10:55:32 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:33 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:33 volumio volumio[1000]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::ClearQueue Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::clearPlayQueue Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:55:33 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::addQueueItems Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::addQueueItems Sep 13 10:55:33 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:33 volumio volumio[1000]: info: Adding Item to queue: webmer/0 Sep 13 10:55:33 volumio volumio[1000]: info: Exploding uri webmer/0 in service motherearthradio Sep 13 10:55:33 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:55:33 volumio volumio[1000]: info: CoreCommandRouter::volumioPlay Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::play index 0 Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::play index undefined Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::startPlaybackTimer Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:33 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:55:33 volumio volumio[1000]: info: sendMpdCommand stop took 1 milliseconds Sep 13 10:55:33 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 10:55:33 volumio volumio[1000]: info: sendMpdCommand clear took 0 milliseconds Sep 13 10:55:33 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 13 10:55:33 volumio volumio[1000]: info: Sep 13 10:55:33 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:33 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:33 volumio volumio[1000]: info: sendMpdCommand consume 1 took 0 milliseconds Sep 13 10:55:33 volumio volumio[1000]: info: [1757775333041] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth/motherearth Sep 13 10:55:33 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" Sep 13 10:55:33 volumio volumio[1000]: info: Sep 13 10:55:33 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:33 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:33 volumio volumio[1000]: info: Sep 13 10:55:33 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:33 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:33 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:55:33 volumio volumio[1000]: info: Sep 13 10:55:33 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:33 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:33 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:55:33 volumio volumio[1000]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" took 2 milliseconds Sep 13 10:55:33 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:55:33 volumio volumio[1000]: info: ------------------------------ 1ms Sep 13 10:55:33 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand play Sep 13 10:55:33 volumio volumio[1000]: info: Sep 13 10:55:33 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:33 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:33 volumio volumio[1000]: info: Sep 13 10:55:33 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:33 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:33 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:55:33 volumio volumio[1000]: info: sendMpdCommand play took 3 milliseconds Sep 13 10:55:33 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:55:33 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:33 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:33 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/2b82bb98005f101e7877ba0b-1702076242.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Martin Kohlstedt - BRU","title":"BRU","artist":"Martin Kohlstedt","album":"Tag","streaming":true,"disableUiControls":true,"duration":126,"seek":0,"bitdepth":"24 bit","channels":2} Sep 13 10:55:33 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 13 10:55:33 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:55:33 volumio volumio[1000]: info: [1757775333755] [MotherEarth] setting new timer with duration of 126000 seconds. Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:33 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:33 volumio volumio[1000]: info: Prefetching next song Sep 13 10:55:34 volumio volumio[1000]: info: Sep 13 10:55:34 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:34 volumio volumio[1000]: info: Sep 13 10:55:34 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:34 volumio volumio[1000]: info: Sep 13 10:55:34 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:34 volumio volumio[1000]: info: Sep 13 10:55:34 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:34 volumio volumio[1000]: info: Sep 13 10:55:34 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:34 volumio volumio[1000]: info: Sep 13 10:55:34 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand clearerror took 4 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Sep 13 10:55:34 volumio volumio[1000]: info: ------------------------------ 15ms Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 13 10:55:34 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand status took 19 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand clearerror took 18 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand clearerror took 18 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Sep 13 10:55:34 volumio volumio[1000]: info: ------------------------------ 42ms Sep 13 10:55:34 volumio volumio[1000]: info: ------------------------------ 41ms Sep 13 10:55:34 volumio volumio[1000]: info: ------------------------------ 42ms Sep 13 10:55:34 volumio volumio[1000]: info: ------------------------------ 39ms Sep 13 10:55:34 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 13 10:55:34 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:34 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:34 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:34 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:34 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Sep 13 10:55:34 volumio volumio[1000]: info: ------------------------------ 44ms Sep 13 10:55:34 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:34 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:34 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:34 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:34 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:39 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 13 10:55:48 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Sep 13 10:55:48 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:49 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::ClearQueue Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::serviceStop Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::serviceStop Sep 13 10:55:49 volumio volumio[1000]: info: ControllerMpd::stop Sep 13 10:55:49 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::clearPlayQueue Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::addQueueItems Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::addQueueItems Sep 13 10:55:49 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:55:49 volumio volumio[1000]: info: Adding Item to queue: webrp/0 Sep 13 10:55:49 volumio volumio[1000]: info: Exploding uri webrp/0 in service radio_paradise Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::volumioPlay Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::play index 0 Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::play index undefined Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::startPlaybackTimer Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:49 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:55:49 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:49 volumio volumio[1000]: info: sendMpdCommand stop took 12 milliseconds Sep 13 10:55:49 volumio volumio[1000]: info: sendMpdCommand stop took 4 milliseconds Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:49 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:55:49 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:55:49 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:49 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received motherearthradio Sep 13 10:55:49 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 10:55:49 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:55:49 volumio volumio[1000]: info: Sep 13 10:55:49 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:49 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:49 volumio volumio[1000]: info: sendMpdCommand clear took 3 milliseconds Sep 13 10:55:49 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 13 10:55:49 volumio volumio[1000]: info: Sep 13 10:55:49 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:49 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:49 volumio volumio[1000]: info: Sep 13 10:55:49 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:49 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:49 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:55:49 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:55:49 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:55:49 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:55:49 volumio volumio[1000]: info: sendMpdCommand consume 1 took 2 milliseconds Sep 13 10:55:49 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:55:49 volumio volumio[1000]: info: ------------------------------ 1ms Sep 13 10:55:49 volumio volumio[1000]: info: [1757775349913] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Sep 13 10:55:49 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Sep 13 10:55:49 volumio volumio[1000]: info: Sep 13 10:55:49 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:49 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:49 volumio volumio[1000]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 1 milliseconds Sep 13 10:55:49 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand play Sep 13 10:55:49 volumio volumio[1000]: info: Sep 13 10:55:49 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:49 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:49 volumio volumio[1000]: info: Sep 13 10:55:49 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:49 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:49 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:55:49 volumio volumio[1000]: info: sendMpdCommand play took 2 milliseconds Sep 13 10:55:49 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:55:49 volumio volumio[1000]: info: [1757775349919] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Sep 13 10:55:49 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:55:50 volumio volumio[1000]: info: [1757775350154] [RadioParadise] received new metadata: {"time":201,"artist":"Kings of Leon","title":"Fans","album":"Because of The Times","year":"2007","cover":"https://img.radioparadise.com/covers/l/B000MRA3NU.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000MRA3NU.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000MRA3NU.jpg"} Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000MRA3NU.jpg","uri":"https://stream.radioparadise.com/flac","name":"Fans","title":"Fans","artist":"Kings of Leon","album":"Because of The Times","streaming":true,"disableUiControls":true,"duration":206,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:55:50 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:55:50 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 13 10:55:50 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:55:50 volumio volumio[1000]: info: [1757775350157] [RadioParadise] setting new timer with duration of 206000 seconds. Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Prefetching next song Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 12ms Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 15ms Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 14ms Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 12ms Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 11ms Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:50 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:50 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:55:50 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:55:50 volumio volumio[1000]: info: Sep 13 10:55:50 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 8ms Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:55:50 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:55:50 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:55:50 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:55:50 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 7ms Sep 13 10:55:50 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:13 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::ClearQueue Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::serviceStop Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::serviceStop Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::stop Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::clearPlayQueue Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::addQueueItems Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::addQueueItems Sep 13 10:56:13 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:13 volumio volumio[1000]: info: Adding Item to queue: webrp/1 Sep 13 10:56:13 volumio volumio[1000]: info: Exploding uri webrp/1 in service radio_paradise Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPlay Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::play index 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::play index undefined Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::startPlaybackTimer Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:56:13 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand stop took 16 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand stop took 9 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000MRA3NU.jpg","uri":"https://stream.radioparadise.com/flac","name":"Fans","title":"Fans","artist":"Kings of Leon","album":"Because of The Times","streaming":true,"disableUiControls":true,"duration":206,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:56:13 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::syncState stateService stop Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:13 volumio volumio[1000]: info: No code Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 9 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand clear took 5 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 9ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:13 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:13 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:13 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:13 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:13 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:13 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:13 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 17ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand consume 1 took 16 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 8ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 7ms Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: info: [1757775373299] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Sep 13 10:56:13 volumio volumio[1000]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 27ms Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand play Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand play took 3 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:13 volumio volumio[1000]: info: [1757775373306] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 7ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 7ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:56:13 volumio volumio[1000]: info: [1757775373536] [RadioParadise] received new metadata: {"time":134,"artist":"Crowded House","title":"Don't Dream It's Over","album":"Crowded House","year":"1986","cover":"https://img.radioparadise.com/covers/l/B000002UCR.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002UCR.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002UCR.jpg"} Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002UCR.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Don't Dream It's Over","title":"Don't Dream It's Over","artist":"Crowded House","album":"Crowded House","streaming":true,"disableUiControls":true,"duration":139,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:56:13 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 13 10:56:13 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:56:13 volumio volumio[1000]: info: [1757775373537] [RadioParadise] setting new timer with duration of 139000 seconds. Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:13 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:13 volumio volumio[1000]: info: Sep 13 10:56:13 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 9ms Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:13 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:13 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:13 volumio volumio[1000]: info: ------------------------------ 9ms Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:13 volumio volumio[1000]: info: Prefetching next song Sep 13 10:56:15 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::ClearQueue Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::serviceStop Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::serviceStop Sep 13 10:56:15 volumio volumio[1000]: info: ControllerMpd::stop Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::clearPlayQueue Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::addQueueItems Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::addQueueItems Sep 13 10:56:15 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:15 volumio volumio[1000]: info: Adding Item to queue: webrp/2 Sep 13 10:56:15 volumio volumio[1000]: info: Exploding uri webrp/2 in service radio_paradise Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPlay Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::play index 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::play index undefined Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::startPlaybackTimer Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:56:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand stop took 19 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002UCR.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Don't Dream It's Over","title":"Don't Dream It's Over","artist":"Crowded House","album":"Crowded House","streaming":true,"disableUiControls":true,"duration":139,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:56:15 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::syncState stateService stop Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:15 volumio volumio[1000]: info: No code Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:15 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand stop took 20 milliseconds Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:15 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:15 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:15 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand status took 6 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand clear took 5 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:15 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:15 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:15 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:15 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:15 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:15 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:15 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:15 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:15 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:15 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 7ms Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand consume 1 took 7 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:15 volumio volumio[1000]: info: [1757775375943] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Sep 13 10:56:15 volumio volumio[1000]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 15ms Sep 13 10:56:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:15 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:15 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 3 milliseconds Sep 13 10:56:15 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand play Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:15 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:15 volumio volumio[1000]: info: Sep 13 10:56:15 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:15 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:56:15 volumio volumio[1000]: info: sendMpdCommand play took 2 milliseconds Sep 13 10:56:15 volumio volumio[1000]: info: [1757775375950] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:56:15 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:56:16 volumio volumio[1000]: info: [1757775376027] [RadioParadise] received new metadata: {"time":29,"artist":"Better Than Ezra","title":"Good","album":"Deluxe","year":"1993","cover":"https://img.radioparadise.com/covers/l/B000005IT2.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000005IT2.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000005IT2.jpg"} Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005IT2.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Good","title":"Good","artist":"Better Than Ezra","album":"Deluxe","streaming":true,"disableUiControls":true,"duration":34,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:56:16 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:56:16 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 13 10:56:16 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:56:16 volumio volumio[1000]: info: [1757775376027] [RadioParadise] setting new timer with duration of 34000 seconds. Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Prefetching next song Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 1 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 6 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 12ms Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 11ms Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 9ms Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 9ms Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:16 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:16 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:16 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:16 volumio volumio[1000]: info: Sep 13 10:56:16 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 3 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand status took 2 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:16 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:16 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:16 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:16 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 5ms Sep 13 10:56:16 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:56:22 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::ClearQueue Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::serviceStop Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::serviceStop Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::stop Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::clearPlayQueue Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::addQueueItems Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::addQueueItems Sep 13 10:56:22 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:22 volumio volumio[1000]: info: Adding Item to queue: webrp/3 Sep 13 10:56:22 volumio volumio[1000]: info: Exploding uri webrp/3 in service radio_paradise Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushQueue Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::saveQueue Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::updateTrackBlock Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrackBlock Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPlay Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::play index 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::stop Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::play index undefined Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::startPlaybackTimer Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 10:56:22 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand stop took 36 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005IT2.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Good","title":"Good","artist":"Better Than Ezra","album":"Deluxe","streaming":true,"disableUiControls":true,"duration":34,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:56:22 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::syncState stateService stop Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:22 volumio volumio[1000]: info: No code Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand stop took 35 milliseconds Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand clear Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:22 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:22 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand clear took 5 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 12ms Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 13ms Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:22 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:22 volumio volumio[1000]: error: updateQueue error: null Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 11ms Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand consume 1 took 10 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:22 volumio volumio[1000]: info: [1757775382794] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/world-etc-flac Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" Sep 13 10:56:22 volumio volumio[1000]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 18ms Sep 13 10:56:22 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:22 volumio volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" took 4 milliseconds Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand play Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 6ms Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand play took 5 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 4ms Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:56:22 volumio volumio[1000]: info: [1757775382805] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 6 milliseconds Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 8 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 14ms Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces state update: player Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::getState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 9ms Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 3ms Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 21ms Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 20ms Sep 13 10:56:22 volumio volumio[1000]: info: Sep 13 10:56:22 volumio volumio[1000]: ---------------------------- MPD announces system playlist update Sep 13 10:56:22 volumio volumio[1000]: info: Ignoring MPD Status Update Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 13 10:56:22 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:22 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:22 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:22 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:22 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 16ms Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:22 volumio volumio[1000]: info: ------------------------------ 2ms Sep 13 10:56:23 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 13 10:56:23 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 13 10:56:23 volumio volumio[1000]: info: ControllerMpd::pushState Sep 13 10:56:23 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:23 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:23 volumio volumio[1000]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 13 10:56:23 volumio volumio[1000]: info: ------------------------------ 10ms Sep 13 10:56:23 volumio volumio[1000]: info: [1757775383027] [RadioParadise] received new metadata: {"time":190,"artist":"Bad Bunny","title":"Tití me preguntó","album":"Un verano sin ti","year":"2022","cover":"https://img.radioparadise.com/covers/l/19725_dc5b6ddd-05e6-4b3f-ab68-9b33d774a4f2.jpg","cover_med":"https://img.radioparadise.com/covers/m/19725_dc5b6ddd-05e6-4b3f-ab68-9b33d774a4f2.jpg","cover_small":"https://img.radioparadise.com/covers/s/19725_dc5b6ddd-05e6-4b3f-ab68-9b33d774a4f2.jpg"} Sep 13 10:56:23 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:23 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 13 10:56:23 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:23 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19725_dc5b6ddd-05e6-4b3f-ab68-9b33d774a4f2.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Tití me preguntó","title":"Tití me preguntó","artist":"Bad Bunny","album":"Un verano sin ti","streaming":true,"disableUiControls":true,"duration":195,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 13 10:56:23 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 13 10:56:23 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 13 10:56:23 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 13 10:56:23 volumio volumio[1000]: info: [1757775383028] [RadioParadise] setting new timer with duration of 195000 seconds. Sep 13 10:56:23 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:23 volumio volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 13 10:56:23 volumio volumio[1000]: info: Prefetching next song Sep 13 10:56:28 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 13 10:56:28 volumio dbus-daemon[637]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.137' (uid=0 pid=19237 comm="timedatectl show --property=NTPSynchronized --valu") Sep 13 10:56:28 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 13 10:56:28 volumio dbus-daemon[637]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 13 10:56:28 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 13 10:56:28 volumio setdatetime-helper.sh[19236]: Time is already synchronized. Sep 13 10:56:28 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 13 10:56:28 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 13 10:56:29 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 13 10:56:29 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:37 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 13 10:56:37 volumio volumio[1000]: info: Preload queue cleared Sep 13 10:56:39 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 13 10:56:39 volumio volumio[1000]: info: READ Sep 13 10:56:39 volumio volumio[1000]: Use XMLElement.setAttribute instead Sep 13 10:56:39 volumio volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 10:56:39 volumio volumio[1000]: TypeError: children[i].attr(...).value is not a function Sep 13 10:56:39 volumio volumio[1000]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 Sep 13 10:56:39 volumio volumio[1000]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 13 10:56:39 volumio volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 10:56:40 volumio sudo[19293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-13 10:55' Sep 13 10:56:40 volumio sudo[19293]: 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"