-- Logs begin at Mon 2025-05-12 07:16:30 UTC, end at Sun 2025-06-08 09:10:55 UTC. -- Jun 08 09:10:24 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 09:10:24 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 09:10:24 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:10:24 volumio-aitendo volumio[976]: info: CoreStateMachine::getQueue Jun 08 09:10:24 volumio-aitendo volumio[976]: info: CorePlayQueue::getQueue Jun 08 09:10:27 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioAddQueueItems Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::addQueueItems Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::addQueueItems Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Adding Item to queue: webrp/0 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Exploding uri webrp/0 in service radio_paradise Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::saveQueue Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushQueue Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::updateTrackBlock Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrackBlock Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPlay Jun 08 09:10:30 volumio-aitendo volumio[976]: UNSET VOLATILE Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::play index 25 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::stop Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:10:30 volumio-aitendo volumio[976]: UNSET VOLATILE Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::stPlaybackTimer Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::updateTrackBlock Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrackBlock Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::pushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::serviceStop Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::serviceStop Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ControllerMpd::stop Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Jun 08 09:10:30 volumio-aitendo volumio[976]: ---------------------------- MPD announces state update: player Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ControllerMpd::getState Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ControllerMpd::getState Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseState Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseState Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseTrackInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseTrackInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ControllerMpd::pushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::servicePushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::pushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::pushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Martin Taylor - Gypsy Medley [4y]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: CURRENT POSITION 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::syncState stateService stop Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::syncState currentStatus stop Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::pushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: info: No code Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::pushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 24 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushState Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ------------------------------ 20ms Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::play index undefined Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CoreStateMachine::startPlaybackTimer Jun 08 09:10:30 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Jun 08 09:10:30 volumio-aitendo volumio[976]: ---------------------------- MPD announces system playlist update Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Ignoring MPD Status Update Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 08 09:10:30 volumio-aitendo volumio[976]: error: updateQueue error: null Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ------------------------------ 2ms Jun 08 09:10:30 volumio-aitendo volumio[976]: info: [1749373830829] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Jun 08 09:10:30 volumio-aitendo volumio[976]: ---------------------------- MPD announces system playlist update Jun 08 09:10:30 volumio-aitendo volumio[976]: info: Ignoring MPD Status Update Jun 08 09:10:30 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand play Jun 08 09:10:30 volumio-aitendo volumio[976]: info: ------------------------------ 4ms Jun 08 09:10:30 volumio-aitendo volumio[976]: info: [1749373830836] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Jun 08 09:10:31 volumio-aitendo volumio[976]: info: [1749373831684] [RadioParadise] received new metadata: {"time":75,"artist":"John Lee Hooker","title":"Boom Boom","album":"Boom Boom","year":"1992","cover":"https://img.radioparadise.com/covers/l/B000M06KG6.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000M06KG6.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000M06KG6.jpg"} Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreCommandRouter::servicePushState Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:31 volumio-aitendo volumio[976]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000M06KG6.jpg","uri":"https://stream.radioparadise.com/flac","name":"Boom Boom","title":"Boom Boom","artist":"John Lee Hooker","album":"Boom Boom","streaming":true,"disableUiControls":true,"duration":80,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 08 09:10:31 volumio-aitendo volumio[976]: verbose: CURRENT POSITION 25 Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreStateMachine::syncState stateService play Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreStateMachine::syncState currentStatus stop Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreStateMachine::pushState Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioPushState Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 09:10:31 volumio-aitendo volumio[976]: info: [1749373831696] [RadioParadise] setting new timer with duration of 80000 seconds. Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioGetQueue Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CoreStateMachine::getQueue Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CorePlayQueue::getQueue Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:31 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 0 Jun 08 09:10:33 volumio-aitendo wpa_supplicant[870]: wlan0: WPA: Group rekeying completed with 54:9b:49:80:b3:77 [GTK=CCMP] Jun 08 09:10:34 volumio-aitendo volumio[976]: info: Jun 08 09:10:34 volumio-aitendo volumio[976]: ---------------------------- MPD announces state update: player Jun 08 09:10:34 volumio-aitendo kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ControllerMpd::getState Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseState Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 09:10:34 volumio-aitendo volumio[976]: info: Jun 08 09:10:34 volumio-aitendo volumio[976]: ---------------------------- MPD announces system playlist update Jun 08 09:10:34 volumio-aitendo volumio[976]: info: Ignoring MPD Status Update Jun 08 09:10:34 volumio-aitendo volumio[976]: info: Jun 08 09:10:34 volumio-aitendo volumio[976]: ---------------------------- MPD announces state update: player Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ControllerMpd::getState Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand status Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseTrackInfo Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ControllerMpd::pushState Jun 08 09:10:34 volumio-aitendo volumio[976]: info: CoreCommandRouter::servicePushState Jun 08 09:10:34 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:34 volumio-aitendo volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ------------------------------ 20ms Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ------------------------------ 3ms Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseState Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 09:10:34 volumio-aitendo volumio[976]: verbose: ControllerMpd::parseTrackInfo Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ControllerMpd::pushState Jun 08 09:10:34 volumio-aitendo volumio[976]: info: CoreCommandRouter::servicePushState Jun 08 09:10:34 volumio-aitendo volumio[976]: info: CorePlayQueue::getTrack 25 Jun 08 09:10:34 volumio-aitendo volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 08 09:10:34 volumio-aitendo volumio[976]: info: ------------------------------ 6ms Jun 08 09:10:49 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jun 08 09:10:51 volumio-aitendo volumio[976]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jun 08 09:10:53 volumio-aitendo volumio[976]: info: CoreCommandRouter::volumioAddQueueItems Jun 08 09:10:53 volumio-aitendo volumio[976]: info: CoreStateMachine::addQueueItems Jun 08 09:10:53 volumio-aitendo volumio[976]: info: CorePlayQueue::addQueueItems Jun 08 09:10:53 volumio-aitendo volumio[976]: info: Adding Item to queue: webkbs/5 Jun 08 09:10:53 volumio-aitendo volumio[976]: info: Exploding uri webkbs/5 in service personal_radio Jun 08 09:10:54 volumio-aitendo volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 09:10:54 volumio-aitendo volumio[976]: ReferenceError: self is not defined Jun 08 09:10:54 volumio-aitendo volumio[976]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Jun 08 09:10:54 volumio-aitendo volumio[976]: at /data/plugins/music_service/personal_radio/index.js:487:12 Jun 08 09:10:54 volumio-aitendo volumio[976]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Jun 08 09:10:54 volumio-aitendo volumio[976]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Jun 08 09:10:54 volumio-aitendo volumio[976]: at emitOne (events.js:116:13) Jun 08 09:10:54 volumio-aitendo volumio[976]: at Request.emit (events.js:211:7) Jun 08 09:10:54 volumio-aitendo volumio[976]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Jun 08 09:10:54 volumio-aitendo volumio[976]: at emitOne (events.js:116:13) Jun 08 09:10:54 volumio-aitendo volumio[976]: at ClientRequest.emit (events.js:211:7) Jun 08 09:10:54 volumio-aitendo volumio[976]: at Socket.socketErrorListener (_http_client.js:387:9) Jun 08 09:10:54 volumio-aitendo volumio[976]: at emitOne (events.js:116:13) Jun 08 09:10:54 volumio-aitendo volumio[976]: at Socket.emit (events.js:211:7) Jun 08 09:10:54 volumio-aitendo volumio[976]: at emitErrorNT (internal/streams/destroy.js:64:8) Jun 08 09:10:54 volumio-aitendo volumio[976]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jun 08 09:10:54 volumio-aitendo volumio[976]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 08 09:10:54 volumio-aitendo volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 09:10:55 volumio-aitendo sudo[14241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 09:09 Jun 08 09:10:55 volumio-aitendo sudo[14241]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="79c2e7e29d04526018e922852ec460ffc0707dd1" VOLUMIO_FE_VERSION="77c86e8a1bfb19b724ead35c47d417c4832cf290" VOLUMIO_BE_VERSION="f30dbcf35403287bd1b5d6a60fa82e3da7871c6c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jul 16 18:40:05 CEST 2020" VOLUMIO_VERSION="2.799" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="3640dc4c81092e5cb33682a0d71ffe92"