Jan 26 10:28:03 volumio volumio[1475]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 10:28:03 volumio volumio[1475]: info: CoreCommandRouter::Close All Modals sent Jan 26 10:28:10 volumio volumio[1475]: info: Preload queue cleared Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::ClearQueue Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::stop Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::stPlaybackTimer Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::updateTrackBlock Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrackBlock Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::pushState Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPushState Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output update for this device Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::serviceStop Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::serviceStop Jan 26 10:28:10 volumio volumio[1475]: info: ControllerMpd::stop Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand stop Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::clearPlayQueue Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::saveQueue Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPushQueue Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::addQueueItems Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::addQueueItems Jan 26 10:28:10 volumio volumio[1475]: info: Preload queue cleared Jan 26 10:28:10 volumio volumio[1475]: info: Adding Item to queue: webrp/13 Jan 26 10:28:10 volumio volumio[1475]: info: Exploding uri webrp/13 in service radio_paradise Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPushQueue Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::saveQueue Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::updateTrackBlock Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrackBlock Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPlay Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::play index 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::stop Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::play index undefined Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::startPlaybackTimer Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 10:28:10 volumio volumio[1475]: info: [RadioParadise] Matched station: Radio Paradise Beyond...(FLAC), chan=5 Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand stop Jan 26 10:28:10 volumio volumio[1475]: info: Jan 26 10:28:10 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand stop took 8 milliseconds Jan 26 10:28:10 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand stop took 3 milliseconds Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::pushState Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPushState Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output update for this device Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/16936.jpg","uri":"http://stream.radioparadise.com/global-flacm","name":"Alaoui","title":"Alaoui","artist":"Orchestre National de Barbès","album":"En Concert","streaming":true,"disableUiControls":true,"duration":311,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 26 10:28:10 volumio volumio[1475]: verbose: CURRENT POSITION 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::syncState stateService stop Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::syncState currentStatus stop Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::pushState Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPushState Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output update for this device Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output Jan 26 10:28:10 volumio volumio[1475]: info: No code Jan 26 10:28:10 volumio volumio[1475]: info: CoreStateMachine::pushState Jan 26 10:28:10 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::volumioPushState Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output update for this device Jan 26 10:28:10 volumio volumio[1475]: info: MRS: Pushing multiroomSync output Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand clear Jan 26 10:28:10 volumio volumio[1475]: info: Jan 26 10:28:10 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:10 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand status took 17 milliseconds Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand clear took 12 milliseconds Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 26 10:28:10 volumio volumio[1475]: error: updateQueue error: null Jan 26 10:28:10 volumio volumio[1475]: info: ------------------------------ 5ms Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand consume 1 took 5 milliseconds Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:10 volumio volumio[1475]: info: [1769416090893] [RadioParadise] set to consume mode, adding url: http://stream.radioparadise.com/beyond-flacm Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/beyond-flacm" Jan 26 10:28:10 volumio volumio[1475]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 26 10:28:10 volumio volumio[1475]: info: ------------------------------ 22ms Jan 26 10:28:10 volumio volumio[1475]: info: Jan 26 10:28:10 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:10 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand add "http://stream.radioparadise.com/beyond-flacm" took 2 milliseconds Jan 26 10:28:10 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand play Jan 26 10:28:10 volumio volumio[1475]: info: ------------------------------ 2ms Jan 26 10:28:10 volumio volumio[1475]: info: sendMpdCommand play took 1 milliseconds Jan 26 10:28:10 volumio volumio[1475]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=5 Jan 26 10:28:10 volumio volumio[1475]: info: [1769416090897] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=5 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 10:28:10 volumio volumio[1475]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Alaoui","artist":"Orchestre National de Barbès","album":"En Concert","duration":"311","uri":"http://stream.radioparadise.com/global-flacm","service":""} Jan 26 10:28:10 volumio volumio[1475]: info: [now-playing] LRCLIB getLyrics(): song: "Alaoui" album: "En Concert" artist: "Orchestre National de Barbès" duration: "311" Jan 26 10:28:10 volumio volumio[1475]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=Alaoui&artist_name=Orchestre+National+de+Barb%C3%A8s&album_name=En+Concert&duration=311 Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 10:28:10 volumio volumio[1475]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise Beyond...(FLAC)","artist":"","duration":"1000","uri":"http://stream.radioparadise.com/beyond-flacm","service":""} Jan 26 10:28:10 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 10:28:10 volumio volumio[1475]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Radio Paradise Beyond...(FLAC)","duration":"1000","uri":"http://stream.radioparadise.com/beyond-flacm","service":""} Jan 26 10:28:11 volumio volumio[1475]: info: Jan 26 10:28:11 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:11 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:11 volumio volumio[1475]: info: Jan 26 10:28:11 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:11 volumio volumio[1475]: info: sendMpdCommand status took 2 milliseconds Jan 26 10:28:11 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:11 volumio volumio[1475]: info: sendMpdCommand status took 0 milliseconds Jan 26 10:28:11 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:11 volumio volumio[1475]: info: ControllerMpd::pushState Jan 26 10:28:11 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:11 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 26 10:28:11 volumio volumio[1475]: info: ------------------------------ 3ms Jan 26 10:28:11 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:11 volumio volumio[1475]: info: ControllerMpd::pushState Jan 26 10:28:11 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:11 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 26 10:28:11 volumio volumio[1475]: info: ------------------------------ 1ms Jan 26 10:28:11 volumio volumio[1475]: info: Jan 26 10:28:11 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:11 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:11 volumio volumio[1475]: info: Jan 26 10:28:11 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:11 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:11 volumio volumio[1475]: info: ------------------------------ 1ms Jan 26 10:28:11 volumio volumio[1475]: info: sendMpdCommand status took 5 milliseconds Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:11 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 26 10:28:11 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:11 volumio volumio[1475]: info: ControllerMpd::pushState Jan 26 10:28:11 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:11 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 26 10:28:11 volumio volumio[1475]: info: ------------------------------ 10ms Jan 26 10:28:11 volumio volumio[1475]: info: [1769416091470] [RadioParadise] received new metadata: {"time":234,"artist":"Łoskot","title":"Piesek Patrzy na Gołębie","album":"Śmierdzące Kwiatuszki","year":"2001","cover":"https://img.radioparadise.com/covers/l/20960.jpg","cover_med":"https://img.radioparadise.com/covers/m/20960.jpg","cover_small":"https://img.radioparadise.com/covers/s/20960.jpg"} Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:11 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:11 volumio volumio[1475]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/20960.jpg","uri":"http://stream.radioparadise.com/beyond-flacm","name":"Piesek Patrzy na Gołębie","title":"Piesek Patrzy na Gołębie","artist":"Łoskot","album":"Śmierdzące Kwiatuszki","streaming":true,"disableUiControls":true,"duration":239,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 26 10:28:11 volumio volumio[1475]: verbose: CURRENT POSITION 0 Jan 26 10:28:11 volumio volumio[1475]: info: CoreStateMachine::syncState stateService play Jan 26 10:28:11 volumio volumio[1475]: info: CoreStateMachine::syncState currentStatus stop Jan 26 10:28:11 volumio volumio[1475]: info: [1769416091471] [RadioParadise] setting new timer with duration of 239000 seconds. Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 1 Jan 26 10:28:12 volumio volumio[1475]: info: Jan 26 10:28:12 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:12 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:12 volumio volumio[1475]: info: Jan 26 10:28:12 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:12 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:12 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:12 volumio volumio[1475]: info: ------------------------------ 2ms Jan 26 10:28:12 volumio volumio[1475]: info: sendMpdCommand status took 1 milliseconds Jan 26 10:28:12 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:12 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:12 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 26 10:28:12 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:12 volumio volumio[1475]: info: ControllerMpd::pushState Jan 26 10:28:12 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:12 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:12 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jan 26 10:28:12 volumio volumio[1475]: info: ------------------------------ 2ms Jan 26 10:28:14 volumio volumio[1475]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=Alaoui&artist_name=Orchestre+National+de+Barb%C3%A8s&album_name=En+Concert&duration=311): Error: Response error: 404 - Not Found Jan 26 10:28:14 volumio volumio[1475]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:20:15) Jan 26 10:28:14 volumio volumio[1475]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 26 10:28:14 volumio volumio[1475]: at async LRCLibAPI.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:33:24) Jan 26 10:28:14 volumio volumio[1475]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Jan 26 10:28:14 volumio volumio[1475]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:135:40 Jan 26 10:28:16 volumio volumio[1475]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 10:28:16 volumio volumio[1475]: info: CoreCommandRouter::Close All Modals sent Jan 26 10:28:48 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Jan 26 10:28:48 volumio volumio[1475]: info: Preload queue cleared Jan 26 10:28:50 volumio volumio[1475]: info: Preload queue cleared Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::ClearQueue Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::stop Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::stPlaybackTimer Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::updateTrackBlock Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrackBlock Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::pushState Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioPushState Jan 26 10:28:50 volumio volumio[1475]: info: MRS: Pushing multiroomSync output update for this device Jan 26 10:28:50 volumio volumio[1475]: info: MRS: Pushing multiroomSync output Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::serviceStop Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::serviceStop Jan 26 10:28:50 volumio volumio[1475]: info: ControllerMpd::stop Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand stop Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::clearPlayQueue Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::saveQueue Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioPushQueue Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::addQueueItems Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::addQueueItems Jan 26 10:28:50 volumio volumio[1475]: info: Preload queue cleared Jan 26 10:28:50 volumio volumio[1475]: info: Adding Item to queue: webmer/0 Jan 26 10:28:50 volumio volumio[1475]: info: Exploding uri webmer/0 in service motherearthradio Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioPushQueue Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::saveQueue Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::updateTrackBlock Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrackBlock Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioPlay Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::play index 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::stop Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::play index undefined Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::startPlaybackTimer Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand stop Jan 26 10:28:50 volumio volumio[1475]: info: Jan 26 10:28:50 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand stop took 14 milliseconds Jan 26 10:28:50 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand stop took 6 milliseconds Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::pushState Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::volumioPushState Jan 26 10:28:50 volumio volumio[1475]: info: MRS: Pushing multiroomSync output update for this device Jan 26 10:28:50 volumio volumio[1475]: info: MRS: Pushing multiroomSync output Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received radio_paradise Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand clear Jan 26 10:28:50 volumio volumio[1475]: info: Jan 26 10:28:50 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:50 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand status took 14 milliseconds Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand clear took 9 milliseconds Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 26 10:28:50 volumio volumio[1475]: error: updateQueue error: null Jan 26 10:28:50 volumio volumio[1475]: info: ------------------------------ 7ms Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand consume 1 took 7 milliseconds Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:50 volumio volumio[1475]: info: [1769416130199] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth/motherearth Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" Jan 26 10:28:50 volumio volumio[1475]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 26 10:28:50 volumio volumio[1475]: info: ------------------------------ 24ms Jan 26 10:28:50 volumio volumio[1475]: info: Jan 26 10:28:50 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:50 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" took 2 milliseconds Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand play Jan 26 10:28:50 volumio volumio[1475]: info: ------------------------------ 4ms Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand play took 3 milliseconds Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 10:28:50 volumio volumio[1475]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Piesek Patrzy na Gołębie","artist":"Łoskot","album":"Śmierdzące Kwiatuszki","duration":"239","uri":"http://stream.radioparadise.com/beyond-flacm","service":""} Jan 26 10:28:50 volumio volumio[1475]: info: [now-playing] LRCLIB getLyrics(): song: "Piesek Patrzy na Gołębie" album: "Śmierdzące Kwiatuszki" artist: "Łoskot" duration: "239" Jan 26 10:28:50 volumio volumio[1475]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=Piesek+Patrzy+na+Go%C5%82%C4%99bie&artist_name=%C5%81oskot&album_name=%C5%9Amierdz%C4%85ce+Kwiatuszki&duration=239 Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 10:28:50 volumio volumio[1475]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Mother Earth Radio (flac)","artist":"","duration":"1000","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","service":""} Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/5c14ff228832207e377e6629-1702324613.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"DePhazz - Astrud Astronette","title":"Astrud Astronette","artist":"DePhazz","album":"Garage Pompeuse","streaming":true,"disableUiControls":true,"duration":232,"seek":0,"bitdepth":"24 bit","channels":2} Jan 26 10:28:50 volumio volumio[1475]: verbose: CURRENT POSITION 0 Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::syncState stateService play Jan 26 10:28:50 volumio volumio[1475]: info: CoreStateMachine::syncState currentStatus stop Jan 26 10:28:50 volumio volumio[1475]: info: [1769416130526] [MotherEarth] setting new timer with duration of 232000 seconds. Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 1 Jan 26 10:28:50 volumio volumio[1475]: info: Jan 26 10:28:50 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:50 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand status took 1 milliseconds Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:50 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 26 10:28:50 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:50 volumio volumio[1475]: info: ControllerMpd::pushState Jan 26 10:28:50 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:50 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:50 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jan 26 10:28:50 volumio volumio[1475]: info: ------------------------------ 2ms Jan 26 10:28:51 volumio volumio[1475]: info: Jan 26 10:28:51 volumio volumio[1475]: ---------------------------- MPD announces system playlist update Jan 26 10:28:51 volumio volumio[1475]: info: Ignoring MPD Status Update Jan 26 10:28:51 volumio volumio[1475]: info: Jan 26 10:28:51 volumio volumio[1475]: ---------------------------- MPD announces state update: player Jan 26 10:28:51 volumio volumio[1475]: info: ControllerMpd::getState Jan 26 10:28:51 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand status Jan 26 10:28:51 volumio volumio[1475]: info: ------------------------------ 1ms Jan 26 10:28:51 volumio volumio[1475]: info: sendMpdCommand status took 0 milliseconds Jan 26 10:28:51 volumio volumio[1475]: verbose: ControllerMpd::parseState Jan 26 10:28:51 volumio volumio[1475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 10:28:51 volumio volumio[1475]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 26 10:28:51 volumio volumio[1475]: verbose: ControllerMpd::parseTrackInfo Jan 26 10:28:51 volumio volumio[1475]: info: ControllerMpd::pushState Jan 26 10:28:51 volumio volumio[1475]: info: CoreCommandRouter::servicePushState Jan 26 10:28:51 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:28:51 volumio volumio[1475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Jan 26 10:28:51 volumio volumio[1475]: info: ------------------------------ 2ms Jan 26 10:28:51 volumio volumio[1475]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=Piesek+Patrzy+na+Go%C5%82%C4%99bie&artist_name=%C5%81oskot&album_name=%C5%9Amierdz%C4%85ce+Kwiatuszki&duration=239): Error: Response error: 404 - Not Found Jan 26 10:28:51 volumio volumio[1475]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:20:15) Jan 26 10:28:51 volumio volumio[1475]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 26 10:28:51 volumio volumio[1475]: at async LRCLibAPI.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:33:24) Jan 26 10:28:51 volumio volumio[1475]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Jan 26 10:28:51 volumio volumio[1475]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:135:40 Jan 26 10:29:07 volumio volumio[1475]: info: CoreCommandRouter::volumioPlay Jan 26 10:29:07 volumio volumio[1475]: info: CoreStateMachine::play index undefined Jan 26 10:29:07 volumio volumio[1475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 10:29:07 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:29:11 volumio volumio[1475]: info: Tunnel connection is inactive, restarting it Jan 26 10:29:11 volumio volumio[1475]: info: Starting Tunnel 1 Jan 26 10:29:11 volumio volumio[1475]: info: Starting Tunnel Connection Checker Jan 26 10:29:11 volumio sudo[16796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 26 10:29:11 volumio sudo[16796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 10:29:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:11 volumio sudo[16796]: pam_unix(sudo:session): session closed for user root Jan 26 10:29:11 volumio autossh[16799]: port set to 0, monitoring disabled Jan 26 10:29:11 volumio autossh[16799]: starting ssh (count 1) Jan 26 10:29:11 volumio autossh[16799]: ssh child pid is 16802 Jan 26 10:29:11 volumio volumio[1475]: info: Remote SSH Started Jan 26 10:29:11 volumio volumio[1475]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 26 10:29:11 volumio volumiossh-tunnel[16802]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Jan 26 10:29:11 volumio autossh[16799]: ssh exited prematurely with status 255; autossh exiting Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 10:29:11 volumio volumio[1475]: info: CoreCommandRouter::volumioGetState Jan 26 10:29:11 volumio volumio[1475]: info: CorePlayQueue::getTrack 0 Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 26 10:29:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:11 volumio autossh[16806]: port set to 0, monitoring disabled Jan 26 10:29:11 volumio autossh[16806]: starting ssh (count 1) Jan 26 10:29:11 volumio autossh[16806]: ssh child pid is 16809 Jan 26 10:29:11 volumio volumiossh-tunnel[16809]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Jan 26 10:29:11 volumio autossh[16806]: ssh exited prematurely with status 255; autossh exiting Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 26 10:29:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:11 volumio autossh[16811]: port set to 0, monitoring disabled Jan 26 10:29:11 volumio autossh[16811]: starting ssh (count 1) Jan 26 10:29:11 volumio autossh[16811]: ssh child pid is 16814 Jan 26 10:29:11 volumio volumiossh-tunnel[16814]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Jan 26 10:29:11 volumio autossh[16811]: ssh exited prematurely with status 255; autossh exiting Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 10:29:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 26 10:29:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:12 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:12 volumio autossh[16816]: port set to 0, monitoring disabled Jan 26 10:29:12 volumio autossh[16816]: starting ssh (count 1) Jan 26 10:29:12 volumio autossh[16816]: ssh child pid is 16819 Jan 26 10:29:12 volumio volumiossh-tunnel[16819]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Jan 26 10:29:12 volumio autossh[16816]: ssh exited prematurely with status 255; autossh exiting Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 26 10:29:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:12 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:12 volumio autossh[16821]: port set to 0, monitoring disabled Jan 26 10:29:12 volumio autossh[16821]: starting ssh (count 1) Jan 26 10:29:12 volumio autossh[16821]: ssh child pid is 16824 Jan 26 10:29:12 volumio volumiossh-tunnel[16824]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Jan 26 10:29:12 volumio autossh[16821]: ssh exited prematurely with status 255; autossh exiting Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 26 10:29:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 26 10:29:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 10:29:12 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 10:29:14 volumio volumio[1475]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 26 10:29:14 volumio volumio[1475]: info: Preload queue cleared Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: http://stream.radioparadise.com/global-flacm Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_instrumental/motherearth.instrumental Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth/motherearth Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/2831240 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/221661931 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/68046254 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/10915836 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/566992 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/94314304 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/68968205 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/227095354 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/282526203 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/6244864 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/4926150 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/2984192 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/4081666 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/479164037 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/476570755 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/288809339 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/4071654 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/10915840 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/1976185 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/145122133 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/401346037 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/68956690 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/4076817 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/13008128 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/108532764 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/572131 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/78123904 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/36424407 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/464422999 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/199100462 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/17580756 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/204869408 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/109860383 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/249939370 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: https://stream.radioparadise.com/flacm Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/88460317 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/24577678 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/33618389 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/312593271 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/33760590 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/230824920 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/311403326 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/413101646 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/1477825 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/402192324 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/87713248 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/428906473 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Kari Bremnes/Kari Bremnes - Gåte Ved Gåte - 2009, DSF 128 (tracks), TAV (AT-2000+LUX)/B2 Lysbroen.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Kari Bremnes/Kari Bremnes - Gåte Ved Gåte - 2009, DSF 128 (tracks), TAV (AT-2000+LUX)/B1 Togsang.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Kari Bremnes/Kari Bremnes - Gåte Ved Gåte - 2009, DSF 128 (tracks), TAV (AT-2000+LUX)/A5 En Bar I Tanger.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Kari Bremnes/Kari Bremnes - Gåte Ved Gåte - 2009, DSF 128 (tracks), TAV (AT-2000+LUX)/A4 I Dine Skap.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Kari Bremnes/Kari Bremnes - Gåte Ved Gåte - 2009, DSF 128 (tracks), TAV (AT-2000+LUX)/A3 Gåte Ved Gåte.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Kari Bremnes/Kari Bremnes - Gåte Ved Gåte - 2009, DSF 128 (tracks), TAV (AT-2000+LUX)/A2 Fiola.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Ida Nielsen - More Sauce, Please! (2023) [FLAC] (24bit-44.1kHz)/07 - Shake It Off.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Ida Nielsen - More Sauce, Please! (2023) [FLAC] (24bit-44.1kHz)/03 - Bounce Back.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/10. James.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/09. Cry Me a River.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/08. Juneteenth.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/05. Who Shot John.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/04. Vessels of Wood and Earth.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/03. The Reconstruction Beat.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/02. Bird of Beauty.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Dan Wilson - Vessels of Wood and Earth (2021) [DSD256]/01. The Rhythm Section.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/10. Carolin No - River.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/09. Carolin No - Rain Rain Go Away.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/08. Carolin No - Belong.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/06. Carolin No - All That I Am.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/03. Carolin No - The Blackbird's Song.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/02. Carolin No - Hear The Silence.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Carolin No/Carolin No - Carolin No (2007)/01. Carolin No - Crystal Ball.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/03_Rosie-from-JL018_64fs-2ch.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/Ralph Towner - Piscean Dance.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/02_Conception-from-JL035_64fs-2ch.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/01_Danse-de-Daphnis-et-Chloe-Danse-de-Dorcon-Danse-fi-from-LSO0899D_64fs-2ch.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/David Munyon - Hollywood Town.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/David Munyon - I Want Your Love.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiorvana-DSD-64-2ch/McKinley Black - My Diamond Mine.flac Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiophile Analog Collection/Audiophile Analog Collection vol. 3 (2024) [DSD512]/12. WISE ONE.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiophile Analog Collection/Audiophile Analog Collection vol. 3 (2024) [DSD512]/7. FANNIE MAE.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiophile Analog Collection/Audiophile Analog Collection vol. 3 (2024) [DSD512]/5. TRAVESIA.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiophile Analog Collection/Audiophile Analog Collection vol. 3 (2024) [DSD512]/2. OUR LOVE IS HERE TO STAY.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: mnt/NAS/384/Audiophile Analog Collection/Audiophile Analog Collection vol. 3 (2024) [DSD512]/1. FOR HEAVEN'S SAKE.dsf Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: cue://NAS/384/2xHD - Audiophile Hi-Res System Test/2xHD Audiophile Hi-Res System Test.cue@0 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/4019951 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/469474088 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/77657003 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/35362249 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/381089289 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/285372575 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/367339836 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/77555349 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/5279070 Jan 26 10:29:14 volumio volumio[1475]: info: Preloading song: tidal://song/283588005 Jan 26 10:29:14 volumio volumio[1475]: info: Exploding uri http://stream.radioparadise.com/global-flacm in service radio_paradise Jan 26 10:29:14 volumio volumio[1475]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/global-flacm" Jan 26 10:29:14 volumio volumio[1475]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 10:29:14 volumio volumio[1475]: TypeError: Cannot read properties of undefined (reading 'then') Jan 26 10:29:14 volumio volumio[1475]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Jan 26 10:29:14 volumio volumio[1475]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 26 10:29:14 volumio volumio[1475]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 26 10:29:14 volumio volumio[1475]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jan 26 10:29:14 volumio volumio[1475]: at listOnTimeout (node:internal/timers:573:17) Jan 26 10:29:14 volumio volumio[1475]: at process.processTimers (node:internal/timers:514:7) Jan 26 10:29:14 volumio volumio[1475]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 10:29:15 volumio sudo[16846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 10:28' Jan 26 10:29:15 volumio sudo[16846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"