-- Logs begin at Thu 2019-02-14 07:11:59 -03, end at Fri 2026-04-03 14:08:07 -03. -- Apr 03 14:07:05 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:05 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:05 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:05 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:05 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:05 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:06 volumio volumio[984]: info: sendMpdCommand status took 25357 milliseconds Apr 03 14:07:06 volumio volumio[984]: info: sendMpdCommand status took 25365 milliseconds Apr 03 14:07:06 volumio volumio[984]: info: sendMpdCommand status took 25365 milliseconds Apr 03 14:07:06 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:06 volumio volumio[984]: info: Command Router : Notfying DB Updatetrue Apr 03 14:07:06 volumio volumio[984]: info: CoreCommandRouter::Close All Modals sent Apr 03 14:07:06 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:06 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:06 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:06 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:09 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 14:07:09 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 14:07:09 volumio volumio[984]: info: Retrieving Cloud Streaming UI Apr 03 14:07:10 volumio volumio[984]: info: Getting Tidal Cloud Configuration Apr 03 14:07:10 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 14:07:10 volumio volumio[984]: info: Getting Qobuz Cloud Configuration Apr 03 14:07:10 volumio volumio[984]: info: Asking plugin for UI Config Apr 03 14:07:10 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 14:07:10 volumio volumio[984]: info: Getting Spotify Cloud Configuration Apr 03 14:07:10 volumio volumio[984]: info: Asking plugin for UI Config Apr 03 14:07:10 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 14:07:10 volumio volumio[984]: info: Saving Spotify Acccount Apr 03 14:07:10 volumio volumio[984]: info: Got Tidal Cloud Configuration Apr 03 14:07:10 volumio volumio[984]: info: Got it Apr 03 14:07:10 volumio volumio[984]: info: Got it Apr 03 14:07:10 volumio volumio[984]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 03 14:07:11 volumio volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 14:07:11 volumio volumio[984]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 14:07:12 volumio volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 14:07:13 volumio volumio[984]: info: CoreCommandRouter::volumioPlay Apr 03 14:07:13 volumio volumio[984]: info: CoreStateMachine::play index undefined Apr 03 14:07:13 volumio volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 14:07:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:13 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 03 14:07:13 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:13 volumio volumio[984]: info: CoreStateMachine::startPlaybackTimer Apr 03 14:07:13 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:13 volumio volumio[984]: info: [1775236033057] ControllerWebradio::clearAddPlayTrack Apr 03 14:07:13 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 14:07:15 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:16 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:16 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:16 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:16 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:16 volumio volumio[984]: Cannot compose Albumart path Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 36297 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 36297 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 33904ms Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 33902 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 33901ms Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 33899 milliseconds Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: info: Command Router : Notfying DB Updatetrue Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::Close All Modals sent Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 22899ms Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 22882 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 22782 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 22781 milliseconds Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 36442ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 36442ms Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand stop took 22125 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand stop took 22124 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 21962 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 21960 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 11135 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 11133 milliseconds Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 36573ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 36572ms Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand stop took 4502 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 285 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 284 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 284 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 284 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 230 milliseconds Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:17 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:17 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:17 volumio volumio[984]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 36659ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 36658ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 34261ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 34259ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 23130ms Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand status took 244 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand clear took 242 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand clear took 241 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 241 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand playlistinfo took 241 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 192ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 190ms Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand load "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand load "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:17 volumio volumio[984]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 03 14:07:17 volumio volumio[984]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 22218ms Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 22216ms Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:17 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:17 volumio volumio[984]: info: Apr 03 14:07:17 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:17 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:17 volumio volumio[984]: info: sendMpdCommand clear took 280 milliseconds Apr 03 14:07:17 volumio volumio[984]: info: ------------------------------ 266ms Apr 03 14:07:17 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand load "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" Apr 03 14:07:19 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:19 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:19 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:19 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 1569ms Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 1568ms Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand playlistinfo took 1562 milliseconds Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand add "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand add "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 1544ms Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 1542ms Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:19 volumio volumio[984]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 1818ms Apr 03 14:07:19 volumio volumio[984]: error: updateQueue error: null Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 1737ms Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand add "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand add "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" took 300 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand add "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" took 299 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand add "http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac" took 3 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand play Apr 03 14:07:19 volumio volumio[984]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand play Apr 03 14:07:19 volumio volumio[984]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand play Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 11ms Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 10ms Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand play took 8 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand play took 7 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand play took 6 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 6ms Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 5ms Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 4ms Apr 03 14:07:19 volumio volumio[984]: info: ------------------------------ 2ms Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand status took 11 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand status took 10 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand status took 9 milliseconds Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:19 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:19 volumio volumio[984]: info: Apr 03 14:07:19 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand status took 17 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:19 volumio volumio[984]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 03 14:07:19 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:19 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand status took 12 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand status took 10 milliseconds Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus stop Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 82ms Apr 03 14:07:20 volumio volumio5-onboarding[1264]: time=2026-04-03T14:07:20.072-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:58092 @ 0x3000720" state=STATUS_PLAYING positionMs=0 volume=18 Apr 03 14:07:20 volumio volumio5-onboarding[1264]: time=2026-04-03T14:07:20.073-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:58092 @ 0x3000720" id=http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac title="FM DE LOS RECUERDOS - La Radio con Memoria" Apr 03 14:07:20 volumio volumio5-onboarding[1264]: time=2026-04-03T14:07:20.074-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:58092 @ 0x3000720" state=STATUS_PLAYING positionMs=0 volume=18 Apr 03 14:07:20 volumio volumio5-onboarding[1264]: time=2026-04-03T14:07:20.074-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:58092 @ 0x3000720" id=http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac title="FM DE LOS RECUERDOS - La Radio con Memoria" Apr 03 14:07:20 volumio volumio5-onboarding[1264]: time=2026-04-03T14:07:20.074-03:00 level=ERROR msg="failed reading message" error="websocket: RSV1 set, RSV2 set" Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 134ms Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 133ms Apr 03 14:07:20 volumio volumio[984]: info: Apr 03 14:07:20 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:20 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:20 volumio volumio[984]: info: Apr 03 14:07:20 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:20 volumio volumio[984]: info: Apr 03 14:07:20 volumio volumio[984]: ---------------------------- MPD announces system playlist update Apr 03 14:07:20 volumio volumio[984]: info: Ignoring MPD Status Update Apr 03 14:07:20 volumio volumio[984]: info: Apr 03 14:07:20 volumio volumio[984]: ---------------------------- MPD announces state update: player Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::getState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand status Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 134ms Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand status took 130 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 133ms Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand status took 129 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 127 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 123 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 123 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 11ms Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand status took 9 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 9ms Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand status took 8 milliseconds Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseState Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 240ms Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 231ms Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 230ms Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 128 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 127 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 127 milliseconds Apr 03 14:07:20 volumio volumio[984]: info: sendMpdCommand playlistinfo took 126 milliseconds Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: verbose: ControllerMpd::parseTrackInfo Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ControllerMpd::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::servicePushState Apr 03 14:07:20 volumio volumio[984]: info: CorePlayQueue::getTrack 0 Apr 03 14:07:20 volumio volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FM DE LOS RECUERDOS - La Radio con Memoria","artist":"FM DE LOS RECUERDOS","album":null,"uri":"http://sonando.us.digitalproserver.com/fmdelosrecuerdos.aac","trackType":"aac"} Apr 03 14:07:20 volumio volumio[984]: verbose: CURRENT POSITION 0 Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState stateService play Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::syncState currentStatus play Apr 03 14:07:20 volumio volumio[984]: info: Received an update from plugin. extracting info from payload Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: CoreStateMachine::pushState Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::volumioPushState Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 367ms Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 367ms Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 243ms Apr 03 14:07:20 volumio volumio[984]: info: ------------------------------ 242ms Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 03 14:07:20 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:24 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:28 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:32 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:36 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:40 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:44 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 14:07:59 volumio volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 14:07:59 volumio volumio[984]: info: CURURI: artists:// Apr 03 14:08:00 volumio volumio[984]: info: Preload queue cleared Apr 03 14:08:06 volumio volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 14:08:06 volumio volumio[984]: TypeError: Cannot read property 'slice' of null Apr 03 14:08:06 volumio volumio[984]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 03 14:08:06 volumio volumio[984]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 03 14:08:06 volumio volumio[984]: at Object.onceWrapper (events.js:422:26) Apr 03 14:08:06 volumio volumio[984]: at ClientRequest.emit (events.js:315:20) Apr 03 14:08:06 volumio volumio[984]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 03 14:08:06 volumio volumio[984]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 03 14:08:06 volumio volumio[984]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 03 14:08:06 volumio volumio[984]: at TLSSocket.emit (events.js:315:20) Apr 03 14:08:06 volumio volumio[984]: at addChunk (internal/streams/readable.js:309:12) Apr 03 14:08:06 volumio volumio[984]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 03 14:08:06 volumio volumio[984]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Apr 03 14:08:06 volumio volumio[984]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 03 14:08:06 volumio volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 14:08:07 volumio sudo[7643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 14:07 Apr 03 14:08:07 volumio sudo[7643]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"