-- Logs begin at Wed 2026-02-25 21:34:30 CST, end at Wed 2026-02-25 21:58:17 CST. -- Feb 25 21:57:00 volumio volumio[1342]: info: [1772027820259] [RadioParadise] received new metadata: {"time":103,"artist":"islandman","title":"Bahar","album":"Kaybola","year":"2019","cover":"https://img.radioparadise.com/covers/l/18887.jpg","cover_med":"https://img.radioparadise.com/covers/m/18887.jpg","cover_small":"https://img.radioparadise.com/covers/s/18887.jpg"} Feb 25 21:57:00 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:00 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:00 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:00 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18887.jpg","uri":"https://stream.radioparadise.com/flac","name":"Bahar","title":"Bahar","artist":"islandman","album":"Kaybola","streaming":true,"disableUiControls":true,"duration":108,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 21:57:00 volumio volumio[1342]: verbose: CURRENT POSITION 0 Feb 25 21:57:00 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play Feb 25 21:57:00 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop Feb 25 21:57:00 volumio volumio[1342]: info: [1772027820264] [RadioParadise] setting new timer with duration of 108000 seconds. Feb 25 21:57:00 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:00 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 Feb 25 21:57:08 volumio volumio[1342]: info: Feb 25 21:57:08 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:08 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:08 volumio volumio[1342]: info: Feb 25 21:57:08 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:08 volumio volumio[1342]: info: sendMpdCommand status took 5 milliseconds Feb 25 21:57:08 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:08 volumio volumio[1342]: info: sendMpdCommand status took 9 milliseconds Feb 25 21:57:08 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:08 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:57:08 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:08 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:08 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:57:08 volumio volumio[1342]: info: ------------------------------ 22ms Feb 25 21:57:08 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 25 21:57:08 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:08 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:57:08 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:08 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:08 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:57:08 volumio volumio[1342]: info: ------------------------------ 20ms Feb 25 21:57:15 volumio volumio[1342]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 25 21:57:15 volumio volumio[1342]: info: Retrying Login Due to Network Error Feb 25 21:57:18 volumio volumio[1342]: info: Feb 25 21:57:18 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:57:18 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:57:18 volumio volumio[1342]: info: Feb 25 21:57:18 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:18 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:18 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:18 volumio volumio[1342]: info: ------------------------------ 3ms Feb 25 21:57:18 volumio volumio[1342]: info: sendMpdCommand status took 1 milliseconds Feb 25 21:57:18 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:18 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:18 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 21:57:18 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:18 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:57:18 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:18 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:18 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:57:18 volumio volumio[1342]: info: ------------------------------ 6ms Feb 25 21:57:23 volumio volumio[1342]: info: VolumeController::SetAlsaVolume72 Feb 25 21:57:23 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:23 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:23 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:57:23 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:23 volumio volumio[1342]: info: CoreCommandRouter::volumioGetQueue Feb 25 21:57:23 volumio volumio[1342]: info: CoreStateMachine::getQueue Feb 25 21:57:23 volumio volumio[1342]: info: CorePlayQueue::getQueue Feb 25 21:57:23 volumio volumio[1342]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 21:57:23 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:57:23 volumio volumio[1342]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Bahar","artist":"islandman","album":"Kaybola","duration":"108","uri":"https://stream.radioparadise.com/flac","service":""} Feb 25 21:57:23 volumio volumio[1342]: info: [now-playing] LRCLIB getLyrics(): song: "Bahar" album: "Kaybola" artist: "islandman" duration: "108" Feb 25 21:57:23 volumio volumio[1342]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=Bahar&artist_name=islandman&album_name=Kaybola&duration=108 Feb 25 21:57:23 volumio volumio[1342]: info: VolumeController::SetAlsaVolume100 Feb 25 21:57:24 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:24 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:24 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:57:24 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:24 volumio volumio[1342]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 21:57:24 volumio volumio[1342]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=Bahar&artist_name=islandman&album_name=Kaybola&duration=108): Error: Response error: 404 - Not Found Feb 25 21:57:24 volumio volumio[1342]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:21:15) Feb 25 21:57:24 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:24 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:24 volumio volumio[1342]: at async Function.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:34:24) Feb 25 21:57:24 volumio volumio[1342]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Feb 25 21:57:24 volumio volumio[1342]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Feb 25 21:57:24 volumio volumio[1342]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:24 volumio volumio[1342]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Feb 25 21:57:24 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:24 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:24 volumio volumio[1342]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Feb 25 21:57:24 volumio volumio[1342]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Feb 25 21:57:24 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:24 volumio volumio[1342]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Feb 25 21:57:24 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:24 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:24 volumio volumio[1342]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Feb 25 21:57:24 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 14) Feb 25 21:57:25 volumio volumio[1342]: info: MyVolumio login type: Token Feb 25 21:57:26 volumio ntpd[1273]: 84.16.73.33 local addr 192.168.0.102 -> Feb 25 21:57:26 volumio volumio[1342]: info: Preload queue cleared Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::ClearQueue Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::stop Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::stPlaybackTimer Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::serviceStop Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::serviceStop Feb 25 21:57:26 volumio volumio[1342]: info: ControllerMpd::stop Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::clearPlayQueue Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::saveQueue Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushQueue Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::addQueueItems Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::addQueueItems Feb 25 21:57:26 volumio volumio[1342]: info: Preload queue cleared Feb 25 21:57:26 volumio volumio[1342]: info: Adding Item to queue: webrp/0 Feb 25 21:57:26 volumio volumio[1342]: info: Using cached record of: webrp/0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushQueue Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::saveQueue Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::play index 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::stop Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::play index undefined Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 21:57:26 volumio volumio[1342]: info: Feb 25 21:57:26 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand stop took 61 milliseconds Feb 25 21:57:26 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand stop took 19 milliseconds Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18887.jpg","uri":"https://stream.radioparadise.com/flac","name":"Bahar","title":"Bahar","artist":"islandman","album":"Kaybola","streaming":true,"disableUiControls":true,"duration":108,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 21:57:26 volumio volumio[1342]: verbose: CURRENT POSITION 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:26 volumio volumio[1342]: info: No code Feb 25 21:57:26 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:26 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear Feb 25 21:57:26 volumio volumio[1342]: info: Feb 25 21:57:26 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:57:26 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand status took 70 milliseconds Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand clear took 43 milliseconds Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 25 21:57:26 volumio volumio[1342]: error: updateQueue error: null Feb 25 21:57:26 volumio volumio[1342]: info: ------------------------------ 12ms Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand consume 1 took 9 milliseconds Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:26 volumio volumio[1342]: info: [1772027846491] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Feb 25 21:57:26 volumio volumio[1342]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 25 21:57:26 volumio volumio[1342]: info: ------------------------------ 88ms Feb 25 21:57:26 volumio volumio[1342]: info: Feb 25 21:57:26 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:57:26 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 6 milliseconds Feb 25 21:57:26 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand play Feb 25 21:57:26 volumio volumio[1342]: info: ------------------------------ 13ms Feb 25 21:57:26 volumio volumio[1342]: info: sendMpdCommand play took 4 milliseconds Feb 25 21:57:26 volumio volumio[1342]: info: [1772027846511] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:57:26 volumio volumio[1342]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise Main Mix (FLAC)","artist":"","duration":"1000","uri":"https://stream.radioparadise.com/flac","service":""} Feb 25 21:57:26 volumio volumio[1342]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:26 volumio volumio[1342]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Feb 25 21:57:26 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:26 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:26 volumio volumio[1342]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Feb 25 21:57:26 volumio volumio[1342]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Feb 25 21:57:26 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:26 volumio volumio[1342]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Feb 25 21:57:26 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:26 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:26 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 16) Feb 25 21:57:26 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:57:26 volumio volumio[1342]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise Main Mix (FLAC)","duration":"1000","uri":"https://stream.radioparadise.com/flac","service":""} Feb 25 21:57:26 volumio volumio[1342]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:26 volumio volumio[1342]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Feb 25 21:57:26 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:26 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:26 volumio volumio[1342]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Feb 25 21:57:26 volumio volumio[1342]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Feb 25 21:57:26 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:26 volumio volumio[1342]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Feb 25 21:57:26 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:26 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:26 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 18) Feb 25 21:57:27 volumio volumio[1342]: info: [1772027847041] [RadioParadise] received new metadata: {"time":77,"artist":"islandman","title":"Bahar","album":"Kaybola","year":"2019","cover":"https://img.radioparadise.com/covers/l/18887.jpg","cover_med":"https://img.radioparadise.com/covers/m/18887.jpg","cover_small":"https://img.radioparadise.com/covers/s/18887.jpg"} Feb 25 21:57:27 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:27 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:27 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:27 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18887.jpg","uri":"https://stream.radioparadise.com/flac","name":"Bahar","title":"Bahar","artist":"islandman","album":"Kaybola","streaming":true,"disableUiControls":true,"duration":82,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 21:57:27 volumio volumio[1342]: verbose: CURRENT POSITION 0 Feb 25 21:57:27 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play Feb 25 21:57:27 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop Feb 25 21:57:27 volumio volumio[1342]: info: [1772027847046] [RadioParadise] setting new timer with duration of 82000 seconds. Feb 25 21:57:32 volumio volumio[1342]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 25 21:57:32 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 25 21:57:32 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:32 volumio volumio[1342]: info: Feb 25 21:57:32 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:32 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:32 volumio volumio[1342]: info: Feb 25 21:57:32 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:32 volumio volumio[1342]: info: sendMpdCommand status took 4 milliseconds Feb 25 21:57:32 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:32 volumio volumio[1342]: info: sendMpdCommand status took 3 milliseconds Feb 25 21:57:32 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:32 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:57:32 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:32 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:32 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:57:32 volumio volumio[1342]: info: ------------------------------ 10ms Feb 25 21:57:32 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 21:57:32 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:32 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:57:32 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:32 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:32 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:57:32 volumio volumio[1342]: info: ------------------------------ 8ms Feb 25 21:57:35 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 25 21:57:35 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:39 volumio volumio[1342]: info: Feb 25 21:57:39 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:57:39 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:57:39 volumio volumio[1342]: info: Feb 25 21:57:39 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:39 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:39 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:39 volumio volumio[1342]: info: ------------------------------ 2ms Feb 25 21:57:39 volumio volumio[1342]: info: sendMpdCommand status took 2 milliseconds Feb 25 21:57:39 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:39 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:39 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 21:57:39 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:39 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:57:39 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:39 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:39 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:57:39 volumio volumio[1342]: info: ------------------------------ 7ms Feb 25 21:57:54 volumio ntpd[1273]: 116.203.151.74 local addr 192.168.0.102 -> Feb 25 21:57:55 volumio volumio[1342]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 25 21:57:55 volumio volumio[1342]: info: Retrying Login Due to Network Error Feb 25 21:57:57 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 25 21:57:57 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: Preload queue cleared Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::ClearQueue Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::stop Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::stPlaybackTimer Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::serviceStop Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::serviceStop Feb 25 21:57:59 volumio volumio[1342]: info: ControllerMpd::stop Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::clearPlayQueue Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::saveQueue Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPushQueue Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::addQueueItems Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::addQueueItems Feb 25 21:57:59 volumio volumio[1342]: info: Preload queue cleared Feb 25 21:57:59 volumio volumio[1342]: info: Adding Item to queue: webrp/1 Feb 25 21:57:59 volumio volumio[1342]: info: Exploding uri webrp/1 in service radio_paradise Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPushQueue Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::saveQueue Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::updateTrackBlock Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrackBlock Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPlay Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::play index 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::stop Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::play index undefined Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::startPlaybackTimer Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 21:57:59 volumio volumio[1342]: info: Feb 25 21:57:59 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand stop took 59 milliseconds Feb 25 21:57:59 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand stop took 17 milliseconds Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18887.jpg","uri":"https://stream.radioparadise.com/flac","name":"Bahar","title":"Bahar","artist":"islandman","album":"Kaybola","streaming":true,"disableUiControls":true,"duration":82,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 21:57:59 volumio volumio[1342]: verbose: CURRENT POSITION 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::syncState stateService stop Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:59 volumio volumio[1342]: info: No code Feb 25 21:57:59 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:57:59 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand clear Feb 25 21:57:59 volumio volumio[1342]: info: Feb 25 21:57:59 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:57:59 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand status took 70 milliseconds Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand clear took 46 milliseconds Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 25 21:57:59 volumio volumio[1342]: error: updateQueue error: null Feb 25 21:57:59 volumio volumio[1342]: info: ------------------------------ 17ms Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand consume 1 took 12 milliseconds Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:57:59 volumio volumio[1342]: info: [1772027879867] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Feb 25 21:57:59 volumio volumio[1342]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 25 21:57:59 volumio volumio[1342]: info: ------------------------------ 94ms Feb 25 21:57:59 volumio volumio[1342]: info: Feb 25 21:57:59 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:57:59 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 17 milliseconds Feb 25 21:57:59 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand play Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:57:59 volumio volumio[1342]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Bahar","artist":"islandman","album":"Kaybola","duration":"82","uri":"https://stream.radioparadise.com/flac","service":""} Feb 25 21:57:59 volumio volumio[1342]: info: [now-playing] LRCLIB getLyrics(): song: "Bahar" album: "Kaybola" artist: "islandman" duration: "82" Feb 25 21:57:59 volumio volumio[1342]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=Bahar&artist_name=islandman&album_name=Kaybola&duration=82 Feb 25 21:57:59 volumio volumio[1342]: info: ------------------------------ 29ms Feb 25 21:57:59 volumio volumio[1342]: info: sendMpdCommand play took 12 milliseconds Feb 25 21:57:59 volumio volumio[1342]: info: [1772027879908] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:57:59 volumio volumio[1342]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise Mellow Mix (FLAC)","artist":"","uri":"https://stream.radioparadise.com/mellow-flac","service":""} Feb 25 21:57:59 volumio volumio[1342]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:59 volumio volumio[1342]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Feb 25 21:57:59 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:59 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:59 volumio volumio[1342]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Feb 25 21:57:59 volumio volumio[1342]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Feb 25 21:57:59 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:59 volumio volumio[1342]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Feb 25 21:57:59 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:59 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:59 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 20) Feb 25 21:57:59 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:57:59 volumio volumio[1342]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise Mellow Mix (FLAC)","uri":"https://stream.radioparadise.com/mellow-flac","service":""} Feb 25 21:57:59 volumio volumio[1342]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:59 volumio volumio[1342]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Feb 25 21:57:59 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:59 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:59 volumio volumio[1342]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Feb 25 21:57:59 volumio volumio[1342]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Feb 25 21:57:59 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:57:59 volumio volumio[1342]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Feb 25 21:57:59 volumio volumio[1342]: at runMicrotasks () Feb 25 21:57:59 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:57:59 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 22) Feb 25 21:58:00 volumio volumio[1342]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=Bahar&artist_name=islandman&album_name=Kaybola&duration=82): Error: Response error: 404 - Not Found Feb 25 21:58:00 volumio volumio[1342]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:21:15) Feb 25 21:58:00 volumio volumio[1342]: at runMicrotasks () Feb 25 21:58:00 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:58:00 volumio volumio[1342]: at async Function.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:34:24) Feb 25 21:58:00 volumio volumio[1342]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Feb 25 21:58:00 volumio volumio[1342]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Feb 25 21:58:00 volumio volumio[1342]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:58:00 volumio volumio[1342]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Feb 25 21:58:00 volumio volumio[1342]: at runMicrotasks () Feb 25 21:58:00 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:58:00 volumio volumio[1342]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Feb 25 21:58:00 volumio volumio[1342]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Feb 25 21:58:00 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Feb 25 21:58:00 volumio volumio[1342]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Feb 25 21:58:00 volumio volumio[1342]: at runMicrotasks () Feb 25 21:58:00 volumio volumio[1342]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 21:58:00 volumio volumio[1342]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Feb 25 21:58:00 volumio volumio[1342]: (node:1342) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 24) Feb 25 21:58:02 volumio volumio[1342]: info: Feb 25 21:58:02 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:58:02 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:58:02 volumio volumio[1342]: info: Feb 25 21:58:02 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:58:02 volumio volumio[1342]: info: sendMpdCommand status took 4 milliseconds Feb 25 21:58:02 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:58:02 volumio volumio[1342]: info: sendMpdCommand status took 2 milliseconds Feb 25 21:58:02 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:58:02 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:58:02 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:58:02 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:02 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:58:02 volumio volumio[1342]: info: ------------------------------ 12ms Feb 25 21:58:02 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 21:58:02 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:58:02 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:58:02 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:58:02 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:02 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:58:02 volumio volumio[1342]: info: ------------------------------ 10ms Feb 25 21:58:04 volumio volumio[1342]: info: [1772027884917] [RadioParadise] received new metadata: {"time":47,"artist":"Patrick Watson","title":"A Mermaid in Lisbon (feat. Teresa Salgueiro)","album":"A Mermaid in Lisbon","year":"2021","cover":"https://img.radioparadise.com/covers/l/25915.jpg","cover_med":"https://img.radioparadise.com/covers/m/25915.jpg","cover_small":"https://img.radioparadise.com/covers/s/25915.jpg"} Feb 25 21:58:04 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:04 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:58:04 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:04 volumio volumio[1342]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/25915.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"A Mermaid in Lisbon (feat. Teresa Salgueiro)","title":"A Mermaid in Lisbon (feat. Teresa Salgueiro)","artist":"Patrick Watson","album":"A Mermaid in Lisbon","streaming":true,"disableUiControls":true,"duration":52,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 21:58:04 volumio volumio[1342]: verbose: CURRENT POSITION 0 Feb 25 21:58:04 volumio volumio[1342]: info: CoreStateMachine::syncState stateService play Feb 25 21:58:04 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus stop Feb 25 21:58:04 volumio volumio[1342]: info: [1772027884923] [RadioParadise] setting new timer with duration of 52000 seconds. Feb 25 21:58:04 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:04 volumio volumio[1342]: info: CorePlayQueue::getTrack 1 Feb 25 21:58:05 volumio volumio[1342]: info: MyVolumio login type: Token Feb 25 21:58:08 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 25 21:58:08 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:09 volumio volumio[1342]: info: Feb 25 21:58:09 volumio volumio[1342]: ---------------------------- MPD announces system playlist update Feb 25 21:58:09 volumio volumio[1342]: info: Ignoring MPD Status Update Feb 25 21:58:09 volumio volumio[1342]: info: Feb 25 21:58:09 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:58:09 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:58:09 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:58:09 volumio volumio[1342]: info: ------------------------------ 3ms Feb 25 21:58:09 volumio volumio[1342]: info: sendMpdCommand status took 2 milliseconds Feb 25 21:58:09 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:58:09 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:58:09 volumio volumio[1342]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 25 21:58:09 volumio volumio[1342]: verbose: ControllerMpd::parseTrackInfo Feb 25 21:58:09 volumio volumio[1342]: info: ControllerMpd::pushState Feb 25 21:58:09 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:58:09 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:09 volumio volumio[1342]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Feb 25 21:58:09 volumio volumio[1342]: info: ------------------------------ 7ms Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 21:58:12 volumio volumio[1342]: Invalid card number. Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 21:58:12 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 21:58:16 volumio volumio[1342]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 25 21:58:16 volumio volumio[1342]: info: Preparing to save Alsa Options, stopping services first Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 25 21:58:16 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::volumioPause Feb 25 21:58:16 volumio volumio[1342]: info: CoreStateMachine::pause Feb 25 21:58:16 volumio volumio[1342]: info: CoreStateMachine::stPlaybackTimer Feb 25 21:58:16 volumio volumio[1342]: info: CoreStateMachine::servicePause Feb 25 21:58:16 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::servicePause Feb 25 21:58:16 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand pause Feb 25 21:58:16 volumio volumio[1342]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 21:58:16 volumio volumio[1342]: info: Found match in Cards Database: setting mixer for card Analog Out Feb 25 21:58:16 volumio volumio[1342]: info: Setting mixer Master for card Analog Out Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 21:58:16 volumio volumio[1342]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 21:58:16 volumio volumio[1342]: info: Disabling external Volume Control Feb 25 21:58:16 volumio volumio[1342]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 21:58:16 volumio volumio[1342]: info: Preparing to generate the ALSA configuration file Feb 25 21:58:17 volumio volumio[1342]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 25 21:58:17 volumio volumio[1342]: info: Reading ALSA contributions from plugins. Feb 25 21:58:17 volumio volumio[1342]: info: Feb 25 21:58:17 volumio volumio[1342]: ---------------------------- MPD announces state update: player Feb 25 21:58:17 volumio volumio[1342]: info: sendMpdCommand pause took 210 milliseconds Feb 25 21:58:17 volumio volumio[1342]: info: ControllerMpd::getState Feb 25 21:58:17 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand status Feb 25 21:58:17 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:17 volumio volumio[1342]: info: CoreCommandRouter::servicePushState Feb 25 21:58:17 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:17 volumio volumio[1342]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/25915.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"A Mermaid in Lisbon (feat. Teresa Salgueiro)","title":"A Mermaid in Lisbon (feat. Teresa Salgueiro)","artist":"Patrick Watson","album":"A Mermaid in Lisbon","streaming":true,"disableUiControls":true,"duration":52,"seek":12987,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 21:58:17 volumio volumio[1342]: verbose: CURRENT POSITION 0 Feb 25 21:58:17 volumio volumio[1342]: info: CoreStateMachine::syncState stateService pause Feb 25 21:58:17 volumio volumio[1342]: info: CoreStateMachine::syncState currentStatus pause Feb 25 21:58:17 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 25 21:58:17 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 25 21:58:17 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 21:58:17 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 25 21:58:17 volumio volumio[1342]: info: CoreStateMachine::stPlaybackTimer Feb 25 21:58:17 volumio volumio[1342]: info: sendMpdCommand status took 44 milliseconds Feb 25 21:58:17 volumio volumio[1342]: verbose: ControllerMpd::parseState Feb 25 21:58:17 volumio volumio[1342]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 21:58:17 volumio volumio[1342]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 21:58:17 volumio volumio[1342]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Feb 25 21:58:17 volumio volumio[1342]: at Object.readdirSync (fs.js:1048:3) Feb 25 21:58:17 volumio volumio[1342]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Feb 25 21:58:17 volumio volumio[1342]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Feb 25 21:58:17 volumio volumio[1342]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Feb 25 21:58:17 volumio volumio[1342]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Feb 25 21:58:17 volumio volumio[1342]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Feb 25 21:58:17 volumio volumio[1342]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 25 21:58:17 volumio volumio[1342]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Feb 25 21:58:17 volumio volumio[1342]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Feb 25 21:58:17 volumio volumio[1342]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Feb 25 21:58:17 volumio volumio[1342]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 25 21:58:17 volumio volumio[1342]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Feb 25 21:58:17 volumio volumio[1342]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Feb 25 21:58:17 volumio volumio[1342]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 25 21:58:17 volumio volumio[1342]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Feb 25 21:58:17 volumio volumio[1342]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Feb 25 21:58:17 volumio volumio[1342]: errno: -20, Feb 25 21:58:17 volumio volumio[1342]: syscall: 'scandir', Feb 25 21:58:17 volumio volumio[1342]: code: 'ENOTDIR', Feb 25 21:58:17 volumio volumio[1342]: path: '/tmp/peppyrunning' Feb 25 21:58:17 volumio volumio[1342]: } Feb 25 21:58:17 volumio volumio[1342]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 21:58:17 volumio sudo[9577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-25 21:57 Feb 25 21:58:17 volumio sudo[9577]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"