-- Logs begin at Sun 2026-05-31 10:42:42 UTC, end at Sun 2026-05-31 11:14:32 UTC. -- May 31 11:13:00 volumio volumio[1074]: info: PLUGIN START: volusonic May 31 11:13:00 volumio volumio[1074]: info: Loading i18n strings for locale fr May 31 11:13:00 volumio volumio[1074]: Updating browse sources language May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceMedia Servers May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceBandcamp Discover May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceJellyfin May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceRadio Nowy Swiat May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourcePandora Radio May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourcePersonal Radio May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourcePodcast May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceRADIO 357 May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceRadio Paradise May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourceSWARM Station May 31 11:13:00 volumio volumio[1074]: Cannot find translation for sourcePhish.in May 31 11:13:00 volumio volumio[1074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 11:13:00 volumio volumio[1074]: info: [1780225980603] CoreMusicLibrary::Adding element Volusonic May 31 11:13:00 volumio volumio[1074]: info: Done. May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesVolusonic May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioClearQueue May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::ClearQueue May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:01 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::stPlaybackTimer May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::getTrack 40 May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:01 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::serviceStop May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::getTrack 40 May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::serviceStop May 31 11:13:01 volumio volumio[1074]: info: [1780225981524] ControllerWebradio::stop May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::clearPlayQueue May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::pushEmptyState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:01 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:13:01 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:13:01 volumio volumio[1074]: info: Disabling plugin volusonic May 31 11:13:01 volumio volumio[1074]: info: sending command... May 31 11:13:01 volumio volumio[1074]: info: Done. May 31 11:13:01 volumio volumio[1074]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99622164","favourite":false} May 31 11:13:01 volumio volumio[1074]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} May 31 11:13:01 volumio volumio[1074]: info: May 31 11:13:01 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:01 volumio volumio[1074]: info: parsing response... May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:01 volumio volumio[1074]: info: sending command... May 31 11:13:01 volumio volumio[1074]: info: parsing response... May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:13:01 volumio volumio[1074]: info: sending command... May 31 11:13:01 volumio volumio[1074]: info: parsing response... May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:13:01 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::getTrack 40 May 31 11:13:01 volumio volumio[1074]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Jamie Jones - Hot Robot Radio","artist":"100 % House and Techno radio station online [techno-music.radio]","album":null,"uri":"http://5.9.13.39:8258/stream","trackType":"39:8258/stream"} May 31 11:13:01 volumio volumio[1074]: info: CURRENT POSITION 40 May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::getTrack 40 May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:01 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:01 volumio volumio[1074]: info: No code May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:01 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:01 volumio volumio[1074]: info: CorePlayQueue::getTrack 40 May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:01 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:01 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:01 volumio volumio[1074]: info: ------------------------------ 110ms May 31 11:13:01 volumio volumio[1074]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} May 31 11:13:01 volumio volumio[1074]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} May 31 11:13:02 volumio wpa_supplicant[938]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN May 31 11:13:03 volumio volumio[1074]: info: Enabling plugin volusonic May 31 11:13:03 volumio volumio[1074]: info: Loading plugin "volusonic"... May 31 11:13:03 volumio volumio[1074]: info: Applying required configuration parameters for plugin volusonic May 31 11:13:03 volumio volumio[1074]: info: PLUGIN START: volusonic May 31 11:13:03 volumio volumio[1074]: info: Loading i18n strings for locale fr May 31 11:13:03 volumio volumio[1074]: Updating browse sources language May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceMedia Servers May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceBandcamp Discover May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceJellyfin May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceRadio Nowy Swiat May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourcePandora Radio May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourcePersonal Radio May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourcePodcast May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceRADIO 357 May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceRadio Paradise May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourceSWARM Station May 31 11:13:03 volumio volumio[1074]: Cannot find translation for sourcePhish.in May 31 11:13:03 volumio volumio[1074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 11:13:03 volumio volumio[1074]: info: [1780225983737] CoreMusicLibrary::Adding element Volusonic May 31 11:13:03 volumio volumio[1074]: info: Done. May 31 11:13:08 volumio volumio[1074]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:32 volumio volumio[1074]: info: No input data May 31 11:13:33 volumio volumio[1074]: Searching all installed plugins May 31 11:13:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: , search May 31 11:13:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 31 11:13:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 31 11:13:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 31 11:13:33 volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 31 11:13:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 31 11:13:33 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: bandcamp , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: jellyfin , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: nowyswiat , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: pandora , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: personal_radio , search May 31 11:13:34 volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin personal_radio May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: podcast , search May 31 11:13:34 volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin podcast May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: rad357pl , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: swarmstation , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumio-phishin , search May 31 11:13:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volusonic , search May 31 11:13:34 volumio volumio[1074]: Search error in Plugin: volusonic. Details: Error: search May 31 11:13:35 volumio volumio[1074]: error: [bandcamp-model.base] getItems(): Unexpected end of JSON input May 31 11:13:35 volumio volumio[1074]: SyntaxError: Unexpected end of JSON input May 31 11:13:35 volumio volumio[1074]: at JSON.parse () May 31 11:13:35 volumio volumio[1074]: at Object.parseImageConstants (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/parser.js:120:25) May 31 11:13:35 volumio volumio[1074]: at _fetchPage.then.html (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/index.js:119:68) May 31 11:13:35 volumio volumio[1074]: at May 31 11:13:35 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:188:7) May 31 11:13:35 volumio volumio[1074]: error: [bandcamp-search] search() error: May 31 11:13:35 volumio volumio[1074]: error: message=Unexpected end of JSON input, stack=SyntaxError: Unexpected end of JSON input May 31 11:13:35 volumio volumio[1074]: at JSON.parse () May 31 11:13:35 volumio volumio[1074]: at Object.parseImageConstants (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/parser.js:120:25) May 31 11:13:35 volumio volumio[1074]: at _fetchPage.then.html (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/index.js:119:68) May 31 11:13:35 volumio volumio[1074]: at May 31 11:13:35 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:188:7) May 31 11:13:36 volumio volumio[1074]: error: [bandcamp-model.base] getItems(): Unexpected end of JSON input May 31 11:13:36 volumio volumio[1074]: SyntaxError: Unexpected end of JSON input May 31 11:13:36 volumio volumio[1074]: at JSON.parse () May 31 11:13:36 volumio volumio[1074]: at Object.parseImageConstants (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/parser.js:120:25) May 31 11:13:36 volumio volumio[1074]: at _fetchPage.then.html (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/index.js:119:68) May 31 11:13:36 volumio volumio[1074]: at May 31 11:13:36 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:188:7) May 31 11:13:36 volumio volumio[1074]: error: [bandcamp-model.base] getItems(): Unexpected end of JSON input May 31 11:13:36 volumio volumio[1074]: SyntaxError: Unexpected end of JSON input May 31 11:13:36 volumio volumio[1074]: at JSON.parse () May 31 11:13:36 volumio volumio[1074]: at Object.parseImageConstants (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/parser.js:120:25) May 31 11:13:36 volumio volumio[1074]: at _fetchPage.then.html (/data/plugins/music_service/bandcamp/node_modules/bandcamp-fetch/lib/index.js:119:68) May 31 11:13:36 volumio volumio[1074]: at May 31 11:13:36 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:188:7) May 31 11:13:38 volumio volumio[1074]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. May 31 11:13:43 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri May 31 11:13:45 volumio volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::addQueueItems May 31 11:13:45 volumio volumio[1074]: info: CorePlayQueue::addQueueItems May 31 11:13:45 volumio volumio[1074]: info: Adding Item to queue: webrp/0 May 31 11:13:45 volumio volumio[1074]: info: Exploding uri webrp/0 in service radio_paradise May 31 11:13:45 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:13:45 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:13:45 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:13:45 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:13:45 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay May 31 11:13:45 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::play index 0 May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:45 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::play index undefined May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:45 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer May 31 11:13:45 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:13:45 volumio volumio[1074]: info: sending command... May 31 11:13:45 volumio volumio[1074]: info: parsing response... May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand clear May 31 11:13:45 volumio volumio[1074]: info: sending command... May 31 11:13:45 volumio volumio[1074]: info: May 31 11:13:45 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:45 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:45 volumio volumio[1074]: info: parsing response... May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand consume 1 May 31 11:13:45 volumio volumio[1074]: info: sending command... May 31 11:13:45 volumio volumio[1074]: info: May 31 11:13:45 volumio volumio[1074]: info: May 31 11:13:45 volumio volumio[1074]: ---------------------------- MPD announces state update: options May 31 11:13:45 volumio volumio[1074]: info: ------------------------------ 5ms May 31 11:13:45 volumio volumio[1074]: info: parsing response... May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:45 volumio volumio[1074]: info: [1780226025649] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" May 31 11:13:45 volumio volumio[1074]: info: sending command... May 31 11:13:45 volumio volumio[1074]: info: sending command... May 31 11:13:45 volumio volumio[1074]: info: May 31 11:13:45 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:45 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:45 volumio volumio[1074]: info: parsing response... May 31 11:13:45 volumio volumio[1074]: info: parsing response... May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand play May 31 11:13:45 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:13:45 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:45 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:45 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:45 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:13:45 volumio volumio[1074]: info: sending command... May 31 11:13:45 volumio volumio[1074]: info: ------------------------------ 15ms May 31 11:13:45 volumio volumio[1074]: info: ------------------------------ 11ms May 31 11:13:45 volumio volumio[1074]: info: parsing response... May 31 11:13:45 volumio volumio[1074]: info: [1780226025666] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 May 31 11:13:46 volumio volumio[1074]: info: [1780226026456] [RadioParadise] received new metadata: {"time":162,"artist":"Temples","title":"Exotico","album":"Exotico","year":"2023","cover":"https://img.radioparadise.com/covers/l/27334.jpg","cover_med":"https://img.radioparadise.com/covers/m/27334.jpg","cover_small":"https://img.radioparadise.com/covers/s/27334.jpg"} May 31 11:13:46 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:46 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:46 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:46 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:46 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:46 volumio volumio[1074]: info: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/27334.jpg","uri":"https://stream.radioparadise.com/flac","name":"Exotico","title":"Exotico","artist":"Temples","album":"Exotico","streaming":true,"disableUiControls":true,"duration":167,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 31 11:13:46 volumio volumio[1074]: info: CURRENT POSITION 0 May 31 11:13:46 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play May 31 11:13:46 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:13:46 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:46 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:46 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:46 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:46 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:46 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:46 volumio volumio[1074]: info: [1780226026522] [RadioParadise] setting new timer with duration of 167000 seconds. May 31 11:13:46 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:13:46 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:46 volumio volumio[1074]: info: CorePlayQueue::getTrack 1 May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::addQueueItems May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::addQueueItems May 31 11:13:52 volumio volumio[1074]: info: Adding Item to queue: webrp/0 May 31 11:13:52 volumio volumio[1074]: info: Exploding uri webrp/0 in service radio_paradise May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:13:52 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay May 31 11:13:52 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::play index 1 May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:52 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::stPlaybackTimer May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:52 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::serviceStop May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::serviceStop May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::stop May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:13:52 volumio volumio[1074]: info: May 31 11:13:52 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:52 volumio volumio[1074]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/27334.jpg","uri":"https://stream.radioparadise.com/flac","name":"Exotico","title":"Exotico","artist":"Temples","album":"Exotico","streaming":true,"disableUiControls":true,"duration":167,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 31 11:13:52 volumio volumio[1074]: info: CURRENT POSITION 0 May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:52 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:52 volumio volumio[1074]: info: No code May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:52 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::play index undefined May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 1 May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 1 May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:13:52 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand clear May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: May 31 11:13:52 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:52 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand consume 1 May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:13:52 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:52 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:52 volumio volumio[1074]: info: CorePlayQueue::getTrack 1 May 31 11:13:52 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: ------------------------------ 109ms May 31 11:13:52 volumio volumio[1074]: info: May 31 11:13:52 volumio volumio[1074]: info: ------------------------------ 11ms May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: [1780226032880] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: May 31 11:13:52 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:52 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand play May 31 11:13:52 volumio volumio[1074]: info: sending command... May 31 11:13:52 volumio volumio[1074]: info: ------------------------------ 6ms May 31 11:13:52 volumio volumio[1074]: info: parsing response... May 31 11:13:52 volumio volumio[1074]: info: [1780226032888] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 May 31 11:13:53 volumio volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::addQueueItems May 31 11:13:53 volumio volumio[1074]: info: CorePlayQueue::addQueueItems May 31 11:13:53 volumio volumio[1074]: info: Adding Item to queue: webrp/4 May 31 11:13:53 volumio volumio[1074]: info: Exploding uri webrp/4 in service radio_paradise May 31 11:13:53 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:13:53 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:13:53 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:13:53 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:13:53 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay May 31 11:13:53 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::play index 2 May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:53 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::play index undefined May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:53 volumio volumio[1074]: info: CorePlayQueue::getTrack 2 May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer May 31 11:13:53 volumio volumio[1074]: info: CorePlayQueue::getTrack 2 May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:13:53 volumio volumio[1074]: info: sending command... May 31 11:13:53 volumio volumio[1074]: info: parsing response... May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand clear May 31 11:13:53 volumio volumio[1074]: info: sending command... May 31 11:13:53 volumio volumio[1074]: info: May 31 11:13:53 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:53 volumio volumio[1074]: info: parsing response... May 31 11:13:53 volumio volumio[1074]: info: sending command... May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" May 31 11:13:53 volumio volumio[1074]: info: sending command... May 31 11:13:53 volumio volumio[1074]: info: May 31 11:13:53 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:53 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:53 volumio volumio[1074]: info: parsing response... May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:13:53 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:53 volumio volumio[1074]: info: CorePlayQueue::getTrack 2 May 31 11:13:53 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:13:53 volumio volumio[1074]: info: ------------------------------ 8ms May 31 11:13:53 volumio volumio[1074]: info: May 31 11:13:53 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:53 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:53 volumio volumio[1074]: info: parsing response... May 31 11:13:53 volumio volumio[1074]: info: ------------------------------ 7ms May 31 11:13:53 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand play May 31 11:13:53 volumio volumio[1074]: info: sending command... May 31 11:13:53 volumio volumio[1074]: info: ------------------------------ 6ms May 31 11:13:53 volumio volumio[1074]: info: parsing response... May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 11:13:53 volumio volumio[1074]: info: [1780226033702] [RadioParadise] received new metadata: {"time":154,"artist":"Temples","title":"Exotico","album":"Exotico","year":"2023","cover":"https://img.radioparadise.com/covers/l/27334.jpg","cover_med":"https://img.radioparadise.com/covers/m/27334.jpg","cover_small":"https://img.radioparadise.com/covers/s/27334.jpg"} May 31 11:13:53 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:56 volumio volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::addQueueItems May 31 11:13:56 volumio volumio[1074]: info: CorePlayQueue::addQueueItems May 31 11:13:56 volumio volumio[1074]: info: Adding Item to queue: webrp/4 May 31 11:13:56 volumio volumio[1074]: info: Exploding uri webrp/4 in service radio_paradise May 31 11:13:56 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:13:56 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:13:56 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:13:56 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:13:56 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay May 31 11:13:56 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::play index 3 May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:56 volumio volumio[1074]: UNSET VOLATILE May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::play index undefined May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:13:56 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer May 31 11:13:56 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:13:56 volumio volumio[1074]: info: sending command... May 31 11:13:56 volumio volumio[1074]: info: May 31 11:13:56 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:56 volumio volumio[1074]: info: parsing response... May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand clear May 31 11:13:56 volumio volumio[1074]: info: sending command... May 31 11:13:56 volumio volumio[1074]: info: sending command... May 31 11:13:56 volumio volumio[1074]: info: May 31 11:13:56 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:56 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:56 volumio volumio[1074]: info: parsing response... May 31 11:13:56 volumio volumio[1074]: info: parsing response... May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" May 31 11:13:56 volumio volumio[1074]: info: sending command... May 31 11:13:56 volumio volumio[1074]: info: sending command... May 31 11:13:56 volumio volumio[1074]: info: May 31 11:13:56 volumio volumio[1074]: info: May 31 11:13:56 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:56 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:56 volumio volumio[1074]: info: ------------------------------ 9ms May 31 11:13:56 volumio volumio[1074]: info: parsing response... May 31 11:13:56 volumio volumio[1074]: info: parsing response... May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand play May 31 11:13:56 volumio volumio[1074]: info: ControllerMpd::pushError May 31 11:13:56 volumio volumio[1074]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined May 31 11:13:56 volumio volumio[1074]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) May 31 11:13:56 volumio volumio[1074]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 31 11:13:56 volumio volumio[1074]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 31 11:13:56 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:180:9) May 31 11:13:56 volumio volumio[1074]: info: sending command... May 31 11:13:56 volumio volumio[1074]: info: ------------------------------ 23ms May 31 11:13:56 volumio volumio[1074]: info: ------------------------------ 12ms May 31 11:13:56 volumio volumio[1074]: info: parsing response... May 31 11:13:56 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 11:13:59 volumio volumio[1074]: info: May 31 11:13:59 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:59 volumio volumio[1074]: info: sending command... May 31 11:13:59 volumio volumio[1074]: info: parsing response... May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:13:59 volumio volumio[1074]: info: sending command... May 31 11:13:59 volumio volumio[1074]: info: May 31 11:13:59 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:59 volumio volumio[1074]: info: sending command... May 31 11:13:59 volumio volumio[1074]: info: May 31 11:13:59 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:59 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:59 volumio volumio[1074]: info: parsing response... May 31 11:13:59 volumio volumio[1074]: info: parsing response... May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:59 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:13:59 volumio volumio[1074]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"aac-320","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":"com/aac-320"} May 31 11:13:59 volumio volumio[1074]: info: CURRENT POSITION 3 May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:59 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:59 volumio volumio[1074]: info: sending command... May 31 11:13:59 volumio volumio[1074]: info: ------------------------------ 103ms May 31 11:13:59 volumio volumio[1074]: info: May 31 11:13:59 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:13:59 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:13:59 volumio volumio[1074]: info: May 31 11:13:59 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:13:59 volumio volumio[1074]: info: ------------------------------ 60ms May 31 11:13:59 volumio volumio[1074]: info: parsing response... May 31 11:13:59 volumio volumio[1074]: info: sending command... May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:13:59 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:13:59 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:13:59 volumio volumio[1074]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Temples - Exotico","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":"com/aac-320"} May 31 11:13:59 volumio volumio[1074]: info: CURRENT POSITION 3 May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus play May 31 11:13:59 volumio volumio[1074]: info: Received an update from plaugin. extracting info from payload May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:59 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:13:59 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:13:59 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:13:59 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:00 volumio volumio[1074]: info: ------------------------------ 134ms May 31 11:14:00 volumio volumio[1074]: info: Pushing Favourites {"service":"webradio","uri":"http://5.9.13.39:8258/stream","favourite":false} May 31 11:14:00 volumio volumio[1074]: info: Pushing Favourites {"service":"webradio","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:00 volumio volumio[1074]: info: Pushing Favourites {"service":"webradio","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:00 volumio volumio[1074]: info: ------------------------------ 145ms May 31 11:14:00 volumio volumio[1074]: info: parsing response... May 31 11:14:00 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:00 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:00 volumio volumio[1074]: info: sending command... May 31 11:14:00 volumio volumio[1074]: info: parsing response... May 31 11:14:00 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:00 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:14:00 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:00 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:14:00 volumio volumio[1074]: info: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Temples - Exotico","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":"com/aac-320"} May 31 11:14:00 volumio volumio[1074]: info: CURRENT POSITION 3 May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus play May 31 11:14:00 volumio volumio[1074]: info: Received an update from plaugin. extracting info from payload May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:00 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:00 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:00 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:00 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:00 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:00 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:00 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:00 volumio volumio[1074]: info: ------------------------------ 336ms May 31 11:14:00 volumio volumio[1074]: info: Pushing Favourites {"service":"webradio","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:00 volumio volumio[1074]: info: Pushing Favourites {"service":"webradio","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::addQueueItems May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::addQueueItems May 31 11:14:03 volumio volumio[1074]: info: Adding Item to queue: webrp/0 May 31 11:14:03 volumio volumio[1074]: info: Exploding uri webrp/0 in service radio_paradise May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:14:03 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay May 31 11:14:03 volumio volumio[1074]: UNSET VOLATILE May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::play index 4 May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:14:03 volumio volumio[1074]: UNSET VOLATILE May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::stPlaybackTimer May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:03 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::serviceStop May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::serviceStop May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::stop May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:03 volumio volumio[1074]: info: May 31 11:14:03 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:14:03 volumio volumio[1074]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/27334.jpg","uri":"https://stream.radioparadise.com/flac","name":"Exotico","title":"Exotico","artist":"Temples","album":"Exotico","streaming":true,"disableUiControls":true,"duration":159,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 31 11:14:03 volumio volumio[1074]: info: CURRENT POSITION 3 May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:03 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:03 volumio volumio[1074]: info: No code May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 3 May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:03 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::play index undefined May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand clear May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:03 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"http://stream-uk1.radioparadise.com/aac-320","favourite":false} May 31 11:14:03 volumio volumio[1074]: info: May 31 11:14:03 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:14:03 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand consume 1 May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:14:03 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:03 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:03 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: ------------------------------ 107ms May 31 11:14:03 volumio volumio[1074]: info: May 31 11:14:03 volumio volumio[1074]: info: ------------------------------ 12ms May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: [1780226043608] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: May 31 11:14:03 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:14:03 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand play May 31 11:14:03 volumio volumio[1074]: info: sending command... May 31 11:14:03 volumio volumio[1074]: info: ------------------------------ 6ms May 31 11:14:03 volumio volumio[1074]: info: parsing response... May 31 11:14:03 volumio volumio[1074]: info: [1780226043617] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 May 31 11:14:04 volumio volumio[1074]: info: [1780226044195] [RadioParadise] received new metadata: {"time":143,"artist":"Temples","title":"Exotico","album":"Exotico","year":"2023","cover":"https://img.radioparadise.com/covers/l/27334.jpg","cover_med":"https://img.radioparadise.com/covers/m/27334.jpg","cover_small":"https://img.radioparadise.com/covers/s/27334.jpg"} May 31 11:14:04 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:04 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:04 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:04 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:04 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:04 volumio volumio[1074]: info: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/27334.jpg","uri":"https://stream.radioparadise.com/flac","name":"Exotico","title":"Exotico","artist":"Temples","album":"Exotico","streaming":true,"disableUiControls":true,"duration":148,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 31 11:14:04 volumio volumio[1074]: info: CURRENT POSITION 4 May 31 11:14:04 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play May 31 11:14:04 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:14:04 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:04 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:04 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:04 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:04 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:04 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:04 volumio volumio[1074]: info: [1780226044226] [RadioParadise] setting new timer with duration of 148000 seconds. May 31 11:14:04 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:14:04 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:04 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:08 volumio volumio[1074]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. May 31 11:14:13 volumio volumio[1074]: info: May 31 11:14:13 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:14:13 volumio volumio[1074]: info: sending command... May 31 11:14:13 volumio volumio[1074]: info: May 31 11:14:13 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:14:13 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:14:13 volumio volumio[1074]: info: May 31 11:14:13 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:14:13 volumio volumio[1074]: info: parsing response... May 31 11:14:13 volumio volumio[1074]: info: sending command... May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:13 volumio volumio[1074]: info: sending command... May 31 11:14:13 volumio volumio[1074]: info: ------------------------------ 36ms May 31 11:14:13 volumio volumio[1074]: info: parsing response... May 31 11:14:13 volumio volumio[1074]: info: parsing response... May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:13 volumio volumio[1074]: info: sending command... May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:14:13 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:13 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:13 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:13 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:14:13 volumio volumio[1074]: info: ------------------------------ 75ms May 31 11:14:13 volumio volumio[1074]: info: parsing response... May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:13 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:14:13 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:13 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:13 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:13 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:14:13 volumio volumio[1074]: info: ------------------------------ 79ms May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::addQueueItems May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::addQueueItems May 31 11:14:15 volumio volumio[1074]: info: Adding Item to queue: webrp/1 May 31 11:14:15 volumio volumio[1074]: info: Exploding uri webrp/1 in service radio_paradise May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::saveQueue May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::volumioPushQueue May 31 11:14:15 volumio volumio[1074]: info: interfaceApi::pushQueue May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::volumioPlay May 31 11:14:15 volumio volumio[1074]: UNSET VOLATILE May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::play index 5 May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::stop May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:14:15 volumio volumio[1074]: UNSET VOLATILE May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::stPlaybackTimer May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:15 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::serviceStop May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::serviceStop May 31 11:14:15 volumio volumio[1074]: info: ControllerMpd::stop May 31 11:14:15 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:14:15 volumio volumio[1074]: info: sending command... May 31 11:14:15 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:14:15 volumio volumio[1074]: info: parsing response... May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:15 volumio volumio[1074]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/27334.jpg","uri":"https://stream.radioparadise.com/flac","name":"Exotico","title":"Exotico","artist":"Temples","album":"Exotico","streaming":true,"disableUiControls":true,"duration":148,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 31 11:14:15 volumio volumio[1074]: info: CURRENT POSITION 4 May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:15 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:15 volumio volumio[1074]: info: No code May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrack 4 May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:15 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:15 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::play index undefined May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:14:15 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:15 volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer May 31 11:14:16 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand stop May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: May 31 11:14:16 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand clear May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:14:16 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/flac","favourite":false} May 31 11:14:16 volumio volumio[1074]: info: May 31 11:14:16 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:14:16 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand consume 1 May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: May 31 11:14:16 volumio volumio[1074]: info: ------------------------------ 15ms May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:16 volumio volumio[1074]: info: [1780226056054] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::pushError May 31 11:14:16 volumio volumio[1074]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined May 31 11:14:16 volumio volumio[1074]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) May 31 11:14:16 volumio volumio[1074]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 31 11:14:16 volumio volumio[1074]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 31 11:14:16 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:180:9) May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: ------------------------------ 57ms May 31 11:14:16 volumio volumio[1074]: info: May 31 11:14:16 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:14:16 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand play May 31 11:14:16 volumio volumio[1074]: info: sending command... May 31 11:14:16 volumio volumio[1074]: info: ------------------------------ 10ms May 31 11:14:16 volumio volumio[1074]: info: parsing response... May 31 11:14:16 volumio volumio[1074]: info: [1780226056076] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 May 31 11:14:16 volumio volumio[1074]: info: [1780226056634] [RadioParadise] received new metadata: {"time":13,"artist":"Sam Phillips","title":"Private Storm","album":"Cruel Inventions","year":"1991","cover":"https://img.radioparadise.com/covers/l/10198.jpg","cover_med":"https://img.radioparadise.com/covers/m/10198.jpg","cover_small":"https://img.radioparadise.com/covers/s/10198.jpg"} May 31 11:14:16 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:16 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:16 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:16 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:16 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:16 volumio volumio[1074]: info: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/10198.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Private Storm","title":"Private Storm","artist":"Sam Phillips","album":"Cruel Inventions","streaming":true,"disableUiControls":true,"duration":18,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 31 11:14:16 volumio volumio[1074]: info: CURRENT POSITION 5 May 31 11:14:16 volumio volumio[1074]: info: CoreStateMachine::syncState stateService play May 31 11:14:16 volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop May 31 11:14:16 volumio volumio[1074]: info: CoreStateMachine::pushState May 31 11:14:16 volumio volumio[1074]: info: CoreStateMachine::getState May 31 11:14:16 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:16 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState May 31 11:14:16 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:14:16 volumio volumio[1074]: info: interfaceApi::pushState May 31 11:14:16 volumio volumio[1074]: info: [1780226056674] [RadioParadise] setting new timer with duration of 18000 seconds. May 31 11:14:16 volumio volumio[1074]: info: Pushing Favourites {"service":"radio_paradise","uri":"https://stream.radioparadise.com/mellow-flac","favourite":false} May 31 11:14:16 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:16 volumio volumio[1074]: info: CorePlayQueue::getTrack 6 May 31 11:14:23 volumio volumio[1074]: info: May 31 11:14:23 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:14:23 volumio volumio[1074]: info: sending command... May 31 11:14:23 volumio volumio[1074]: info: parsing response... May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:23 volumio volumio[1074]: info: sending command... May 31 11:14:23 volumio volumio[1074]: info: May 31 11:14:23 volumio volumio[1074]: ---------------------------- MPD announces state update: player May 31 11:14:23 volumio volumio[1074]: info: May 31 11:14:23 volumio volumio[1074]: ---------------------------- MPD announces system playlist update May 31 11:14:23 volumio volumio[1074]: info: Ignoring MPD Status Update May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::getState May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand status May 31 11:14:23 volumio volumio[1074]: info: parsing response... May 31 11:14:23 volumio volumio[1074]: info: sending command... May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:14:23 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:23 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:23 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:23 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:14:23 volumio volumio[1074]: info: ------------------------------ 18ms May 31 11:14:23 volumio volumio[1074]: info: parsing response... May 31 11:14:23 volumio volumio[1074]: info: ------------------------------ 11ms May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::parseState May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::sendMpdCommand playlistinfo May 31 11:14:23 volumio volumio[1074]: info: sending command... May 31 11:14:23 volumio volumio[1074]: info: parsing response... May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::parseTrackInfo May 31 11:14:23 volumio volumio[1074]: info: ControllerMpd::pushState May 31 11:14:23 volumio volumio[1074]: info: CoreCommandRouter::servicePushState May 31 11:14:23 volumio volumio[1074]: info: CoreStateMachine::syncState May 31 11:14:23 volumio volumio[1074]: info: CorePlayQueue::getTrack 5 May 31 11:14:23 volumio volumio[1074]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 31 11:14:23 volumio volumio[1074]: info: ------------------------------ 17ms May 31 11:14:30 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: pandora , handleBrowseUri May 31 11:14:30 volumio volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:14:30 volumio volumio[1074]: TypeError: Cannot read property 'getStationData' of undefined May 31 11:14:30 volumio volumio[1074]: at ControllerPandora.handleBrowseUri (/data/plugins/music_service/pandora/index.js:295:45) May 31 11:14:30 volumio volumio[1074]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1000:29) May 31 11:14:30 volumio volumio[1074]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:328:47) May 31 11:14:30 volumio volumio[1074]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:354:32) May 31 11:14:30 volumio volumio[1074]: at emitTwo (events.js:126:13) May 31 11:14:30 volumio volumio[1074]: at Socket.emit (events.js:214:7) May 31 11:14:30 volumio volumio[1074]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 31 11:14:30 volumio volumio[1074]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 31 11:14:30 volumio volumio[1074]: at process._tickCallback (internal/process/next_tick.js:180:9) May 31 11:14:30 volumio volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:14:32 volumio sudo[14998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 11:13 May 31 11:14:32 volumio sudo[14998]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="2f09878fca434492a3a1c8d64c992c38a304bdcb" VOLUMIO_FE_VERSION="81c7100e9c7342b16fc9e0ca5908a26771e08c4f" VOLUMIO_BE_VERSION="9ddaa4edade219b4424aaf553d8a153e2d73143a" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jun 15 20:45:03 CEST 2018" VOLUMIO_VERSION="2.411" VOLUMIO_HARDWARE="x86"