-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2025-03-09 21:29:47 UTC. -- Mar 09 21:28:03 volumio volumio[823]: info: [1741555683343] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Mar 09 21:28:03 volumio volumio[823]: info: [1741555683969] [RadioParadise] received new metadata: {"time":309,"artist":"Dengue Fever","title":"Uku","album":"Cannibal Courtship","year":"2011","cover":"https://img.radioparadise.com/covers/l/B004KU2DVU.jpg","cover_med":"https://img.radioparadise.com/covers/m/B004KU2DVU.jpg","cover_small":"https://img.radioparadise.com/covers/s/B004KU2DVU.jpg"} Mar 09 21:28:03 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:03 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:28:03 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:03 volumio volumio[823]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B004KU2DVU.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Uku","title":"Uku","artist":"Dengue Fever","album":"Cannibal Courtship","streaming":true,"disableUiControls":true,"duration":314,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:28:03 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:28:03 volumio volumio[823]: info: CoreStateMachine::syncState stateService play Mar 09 21:28:03 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus play Mar 09 21:28:03 volumio volumio[823]: info: Received an update from plugin. extracting info from payload Mar 09 21:28:03 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:03 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:03 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:28:03 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:03 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:03 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:03 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:03 volumio volumio[823]: info: [1741555683995] [RadioParadise] setting new timer with duration of 314000 seconds. Mar 09 21:28:04 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:04 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:04 volumio volumio[823]: info: Prefetching next song Mar 09 21:28:07 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:28:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:10 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 09 21:28:10 volumio volumio[823]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 09 21:28:10 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 09 21:28:10 volumio volumio[823]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Mar 09 21:28:10 volumio volumio[823]: info: Received Get System Version Mar 09 21:28:10 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 09 21:28:17 volumio ntpd[589]: 217.144.138.234 local addr 192.168.178.26 -> Mar 09 21:28:17 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:28:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:17 volumio volumio[823]: info: Listing playlists Mar 09 21:28:17 volumio volumio[823]: info: Listing playlists Mar 09 21:28:27 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:28:27 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:33 volumio ntpd[589]: 45.9.61.155 local addr 192.168.178.26 -> Mar 09 21:28:37 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:28:37 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:37 volumio volumio[823]: info: Listing playlists Mar 09 21:28:37 volumio volumio[823]: info: Listing playlists Mar 09 21:28:38 volumio volumio[823]: info: CoreCommandRouter::volumioGetVisibleSources Mar 09 21:28:38 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 09 21:28:38 volumio volumio[823]: info: CoreCommandRouter::volumioGetQueue Mar 09 21:28:38 volumio volumio[823]: info: CoreStateMachine::getQueue Mar 09 21:28:38 volumio volumio[823]: info: CorePlayQueue::getQueue Mar 09 21:28:38 volumio nmbd[584]: [2025/03/09 21:28:38.959409, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 09 21:28:38 volumio nmbd[584]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.26 for name WORKGROUP<1d>. Mar 09 21:28:38 volumio nmbd[584]: This response was from IP 192.168.178.21, reporting an IP address of 192.168.178.21. Mar 09 21:28:42 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Mar 09 21:28:47 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:28:47 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::ClearQueue Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:28:52 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::stPlaybackTimer Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::serviceStop Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::serviceStop Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::stop Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::clearPlayQueue Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::addQueueItems Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::addQueueItems Mar 09 21:28:52 volumio volumio[823]: info: Adding Item to queue: webrp/0 Mar 09 21:28:52 volumio volumio[823]: info: Exploding uri webrp/0 in service radio_paradise Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPlay Mar 09 21:28:52 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::play index 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:28:52 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::play index undefined Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::startPlaybackTimer Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand stop took 65 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand stop took 39 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B004KU2DVU.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"Uku","title":"Uku","artist":"Dengue Fever","album":"Cannibal Courtship","streaming":true,"disableUiControls":true,"duration":314,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:28:52 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::syncState stateService stop Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus stop Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:52 volumio volumio[823]: info: No code Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:28:52 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand status took 61 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand clear took 38 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 09 21:28:52 volumio volumio[823]: error: updateQueue error: null Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 18ms Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand consume 1 took 13 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:28:52 volumio volumio[823]: info: [1741555732304] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Mar 09 21:28:52 volumio volumio[823]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 85ms Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:28:52 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 21 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand play Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 13ms Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand play took 5 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: [1741555732339] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:28:52 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand status took 16 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand status took 2 milliseconds Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 21ms Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 7ms Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:28:52 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:28:52 volumio volumio[823]: info: Mar 09 21:28:52 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 3ms Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand status took 1 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:28:52 volumio volumio[823]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 21:28:52 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:28:52 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:28:52 volumio volumio[823]: info: ------------------------------ 6ms Mar 09 21:28:52 volumio volumio[823]: info: [1741555732897] [RadioParadise] received new metadata: {"time":107,"artist":"Elbow","title":"Fly Boy Blue / Lunette","album":"The Take Off and Landing of Everything","year":"2014","cover":"https://img.radioparadise.com/covers/l/B00HZVX0Y0.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00HZVX0Y0.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00HZVX0Y0.jpg"} Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00HZVX0Y0.jpg","uri":"https://stream.radioparadise.com/flac","name":"Fly Boy Blue / Lunette","title":"Fly Boy Blue / Lunette","artist":"Elbow","album":"The Take Off and Landing of Everything","streaming":true,"disableUiControls":true,"duration":112,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:28:52 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::syncState stateService play Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus stop Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:28:52 volumio volumio[823]: info: [1741555732907] [RadioParadise] setting new timer with duration of 112000 seconds. Mar 09 21:28:52 volumio volumio[823]: info: CoreCommandRouter::volumioGetQueue Mar 09 21:28:52 volumio volumio[823]: info: CoreStateMachine::getQueue Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getQueue Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:52 volumio volumio[823]: info: Prefetching next song Mar 09 21:28:57 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:28:57 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:28:57 volumio volumio[823]: info: Listing playlists Mar 09 21:28:57 volumio volumio[823]: info: Listing playlists Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::ClearQueue Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:07 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::stPlaybackTimer Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::serviceStop Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::serviceStop Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::stop Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::clearPlayQueue Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::addQueueItems Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::addQueueItems Mar 09 21:29:07 volumio volumio[823]: info: Adding Item to queue: webrp/1 Mar 09 21:29:07 volumio volumio[823]: info: Exploding uri webrp/1 in service radio_paradise Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPlay Mar 09 21:29:07 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::play index 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:07 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::play index undefined Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::startPlaybackTimer Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand stop took 58 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00HZVX0Y0.jpg","uri":"https://stream.radioparadise.com/flac","name":"Fly Boy Blue / Lunette","title":"Fly Boy Blue / Lunette","artist":"Elbow","album":"The Take Off and Landing of Everything","streaming":true,"disableUiControls":true,"duration":112,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:29:07 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::syncState stateService stop Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus stop Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:07 volumio volumio[823]: info: No code Mar 09 21:29:07 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand stop took 55 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand status took 27 milliseconds Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:29:07 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand clear took 9 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:07 volumio volumio[823]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 40ms Mar 09 21:29:07 volumio volumio[823]: error: updateQueue error: null Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 7ms Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand consume 1 took 5 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: [1741555747489] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:29:07 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 3 milliseconds Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand play Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 17ms Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand play took 7 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: [1741555747509] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:07 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand status took 15 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand status took 3 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 23ms Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:29:07 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:29:07 volumio volumio[823]: info: Mar 09 21:29:07 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 11ms Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 4ms Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand status took 3 milliseconds Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:07 volumio volumio[823]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 09 21:29:07 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:07 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:29:07 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:07 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:07 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:29:07 volumio volumio[823]: info: ------------------------------ 7ms Mar 09 21:29:08 volumio volumio[823]: info: [1741555748068] [RadioParadise] received new metadata: {"time":120,"artist":"Joan Osborne","title":"Brokedown Palace","album":"Pretty Little Stranger","year":"2006","cover":"https://img.radioparadise.com/covers/l/B000ICLRAM.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000ICLRAM.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000ICLRAM.jpg"} Mar 09 21:29:08 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:08 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:08 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:08 volumio volumio[823]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000ICLRAM.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Brokedown Palace","title":"Brokedown Palace","artist":"Joan Osborne","album":"Pretty Little Stranger","streaming":true,"disableUiControls":true,"duration":125,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:29:08 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:29:08 volumio volumio[823]: info: CoreStateMachine::syncState stateService play Mar 09 21:29:08 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus stop Mar 09 21:29:08 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:08 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:08 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:08 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:08 volumio volumio[823]: info: [1741555748078] [RadioParadise] setting new timer with duration of 125000 seconds. Mar 09 21:29:08 volumio volumio[823]: info: CoreCommandRouter::volumioGetQueue Mar 09 21:29:08 volumio volumio[823]: info: CoreStateMachine::getQueue Mar 09 21:29:08 volumio volumio[823]: info: CorePlayQueue::getQueue Mar 09 21:29:08 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:08 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:08 volumio volumio[823]: info: Prefetching next song Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: Listing playlists Mar 09 21:29:17 volumio volumio[823]: info: Listing playlists Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::ClearQueue Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:17 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::stPlaybackTimer Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::serviceStop Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::serviceStop Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::stop Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::clearPlayQueue Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::addQueueItems Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::addQueueItems Mar 09 21:29:17 volumio volumio[823]: info: Adding Item to queue: webrp/2 Mar 09 21:29:17 volumio volumio[823]: info: Exploding uri webrp/2 in service radio_paradise Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPlay Mar 09 21:29:17 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::play index 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:17 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::play index undefined Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::startPlaybackTimer Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand stop took 40 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand stop took 16 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000ICLRAM.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Brokedown Palace","title":"Brokedown Palace","artist":"Joan Osborne","album":"Pretty Little Stranger","streaming":true,"disableUiControls":true,"duration":125,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:29:17 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::syncState stateService stop Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus stop Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:17 volumio volumio[823]: info: No code Mar 09 21:29:17 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:29:17 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand status took 27 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand clear took 16 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 09 21:29:17 volumio volumio[823]: error: updateQueue error: null Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 10ms Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand consume 1 took 9 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:17 volumio volumio[823]: info: [1741555757600] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Mar 09 21:29:17 volumio volumio[823]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 40ms Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:29:17 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 4 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand play Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 16ms Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand play took 4 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: [1741555757620] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:17 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand status took 15 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand status took 3 milliseconds Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 22ms Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 8ms Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces system playlist update Mar 09 21:29:17 volumio volumio[823]: info: Ignoring MPD Status Update Mar 09 21:29:17 volumio volumio[823]: info: Mar 09 21:29:17 volumio volumio[823]: ---------------------------- MPD announces state update: player Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::getState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand status Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 3ms Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand status took 1 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseState Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 21:29:17 volumio volumio[823]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 21:29:17 volumio volumio[823]: verbose: ControllerMpd::parseTrackInfo Mar 09 21:29:17 volumio volumio[823]: info: ControllerMpd::pushState Mar 09 21:29:17 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:17 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:17 volumio volumio[823]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 09 21:29:17 volumio volumio[823]: info: ------------------------------ 6ms Mar 09 21:29:18 volumio volumio[823]: info: [1741555758227] [RadioParadise] received new metadata: {"time":140,"artist":"Cage the Elephant","title":"Neon Pill","album":"Neon Pill","year":"2024","cover":"https://img.radioparadise.com/covers/l/19495_47a434be-e8aa-40b3-86ce-2d943af4b4e9.jpg","cover_med":"https://img.radioparadise.com/covers/m/19495_47a434be-e8aa-40b3-86ce-2d943af4b4e9.jpg","cover_small":"https://img.radioparadise.com/covers/s/19495_47a434be-e8aa-40b3-86ce-2d943af4b4e9.jpg"} Mar 09 21:29:18 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:18 volumio volumio[823]: info: CoreCommandRouter::servicePushState Mar 09 21:29:18 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:18 volumio volumio[823]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19495_47a434be-e8aa-40b3-86ce-2d943af4b4e9.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Neon Pill","title":"Neon Pill","artist":"Cage the Elephant","album":"Neon Pill","streaming":true,"disableUiControls":true,"duration":145,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 09 21:29:18 volumio volumio[823]: verbose: CURRENT POSITION 0 Mar 09 21:29:18 volumio volumio[823]: info: CoreStateMachine::syncState stateService play Mar 09 21:29:18 volumio volumio[823]: info: CoreStateMachine::syncState currentStatus stop Mar 09 21:29:18 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:18 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:18 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:18 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:18 volumio volumio[823]: info: [1741555758237] [RadioParadise] setting new timer with duration of 145000 seconds. Mar 09 21:29:18 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:18 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:18 volumio volumio[823]: info: Prefetching next song Mar 09 21:29:18 volumio volumio[823]: info: CoreCommandRouter::volumioGetQueue Mar 09 21:29:18 volumio volumio[823]: info: CoreStateMachine::getQueue Mar 09 21:29:18 volumio volumio[823]: info: CorePlayQueue::getQueue Mar 09 21:29:27 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:29:27 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:35 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 09 21:29:37 volumio volumio[823]: info: CoreCommandRouter::volumioGetState Mar 09 21:29:37 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:37 volumio volumio[823]: info: Listing playlists Mar 09 21:29:37 volumio volumio[823]: info: Listing playlists Mar 09 21:29:39 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 09 21:29:44 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 09 21:29:46 volumio volumio[823]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::ClearQueue Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::stop Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 21:29:46 volumio volumio[823]: verbose: UNSET VOLATILE Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::stPlaybackTimer Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::updateTrackBlock Mar 09 21:29:46 volumio volumio[823]: info: CorePlayQueue::getTrackBlock Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::pushState Mar 09 21:29:46 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:46 volumio volumio[823]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 21:29:46 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::serviceStop Mar 09 21:29:46 volumio volumio[823]: info: CorePlayQueue::getTrack 0 Mar 09 21:29:46 volumio volumio[823]: info: CoreCommandRouter::serviceStop Mar 09 21:29:46 volumio volumio[823]: info: ControllerMpd::stop Mar 09 21:29:46 volumio volumio[823]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 21:29:46 volumio volumio[823]: info: CorePlayQueue::clearPlayQueue Mar 09 21:29:46 volumio volumio[823]: info: CorePlayQueue::saveQueue Mar 09 21:29:46 volumio volumio[823]: info: CoreCommandRouter::volumioPushState Mar 09 21:29:46 volumio volumio[823]: info: CoreCommandRouter::volumioPushQueue Mar 09 21:29:46 volumio volumio[823]: info: CoreStateMachine::addQueueItems Mar 09 21:29:46 volumio volumio[823]: info: CorePlayQueue::addQueueItems Mar 09 21:29:46 volumio volumio[823]: info: Adding Item to queue: webkbs/1 Mar 09 21:29:46 volumio volumio[823]: info: Exploding uri webkbs/1 in service personal_radio Mar 09 21:29:46 volumio volumio[823]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 21:29:46 volumio volumio[823]: ReferenceError: self is not defined Mar 09 21:29:46 volumio volumio[823]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Mar 09 21:29:46 volumio volumio[823]: at /data/plugins/music_service/personal_radio/index.js:487:12 Mar 09 21:29:46 volumio volumio[823]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Mar 09 21:29:46 volumio volumio[823]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Mar 09 21:29:46 volumio volumio[823]: at Request.emit (events.js:315:20) Mar 09 21:29:46 volumio volumio[823]: at Request.init (/data/plugins/music_service/personal_radio/node_modules/request/request.js:233:17) Mar 09 21:29:46 volumio volumio[823]: at new Request (/data/plugins/music_service/personal_radio/node_modules/request/request.js:129:8) Mar 09 21:29:46 volumio volumio[823]: at Function.request (/data/plugins/music_service/personal_radio/node_modules/request/index.js:55:10) Mar 09 21:29:46 volumio volumio[823]: at Object.end (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:595:27) Mar 09 21:29:46 volumio volumio[823]: at ControllerPersonalRadio.getStreamUrl (/data/plugins/music_service/personal_radio/index.js:482:4) Mar 09 21:29:46 volumio volumio[823]: at ControllerPersonalRadio.explodeUri (/data/plugins/music_service/personal_radio/index.js:355:12) Mar 09 21:29:46 volumio volumio[823]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1324:16) Mar 09 21:29:46 volumio volumio[823]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) Mar 09 21:29:46 volumio volumio[823]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:243:25) Mar 09 21:29:46 volumio volumio[823]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:487:23) Mar 09 21:29:46 volumio volumio[823]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:457:17) Mar 09 21:29:46 volumio volumio[823]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 21:29:47 volumio sudo[4199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 21:28 Mar 09 21:29:47 volumio sudo[4199]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="682e0b17433cee9f9da31457f545345105e06e63" VOLUMIO_FE_VERSION="fba46add594d630e1b506bc42f1beddaad53fb64" VOLUMIO_FE3_VERSION="c23fef6dba8e02db978bd39bd8d26872e60ada6e" VOLUMIO_BE_VERSION="f7885966dee9f148ddf4f5050103a7d6734e1a10" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Dec 2021 04:57:50 PM CET" VOLUMIO_VERSION="3.179" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6d5d4c489f0b1e608d9cefb791a9102d"