-- Logs begin at Thu 2026-04-02 13:26:20 UTC, end at Thu 2026-04-02 16:06:29 UTC. -- Apr 02 16:05:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:23 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:23 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:23 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:24 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:24 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:24 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:24 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:24 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:24 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:24 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:25 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:25 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 02 16:05:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:26 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:26 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:26 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:26 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:26 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:28 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:28 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:28 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:28 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:28 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:28 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:28 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:28 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:30 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:30 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:30 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:30 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:34 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:34 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:05:34 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:05:35 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 02 16:05:35 volumio sudo[24253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 02 16:05:35 volumio sudo[24253]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:35 volumio sudo[24253]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:35 volumio sudo[24265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 02 16:05:35 volumio sudo[24265]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:35 volumio sudo[24265]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:35 volumio sudo[24259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 02 16:05:35 volumio sudo[24259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:35 volumio sudo[24259]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:35 volumio sudo[24273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 02 16:05:35 volumio sudo[24273]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:35 volumio sudo[24273]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:35 volumio sudo[24280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 02 16:05:35 volumio sudo[24280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:35 volumio sudo[24280]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:35 volumio sudo[24281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 02 16:05:35 volumio sudo[24281]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:35 volumio sudo[24281]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:36 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:36 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:05:36 volumio volumio[954]: info: Discovery: Getting this device information Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:36 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:36 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:05:36 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:05:39 volumio sudo[24294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 02 16:05:39 volumio sudo[24294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:39 volumio sudo[24294]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:39 volumio sudo[24296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 02 16:05:39 volumio sudo[24296]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:39 volumio sudo[24296]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:40 volumio sudo[24300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 02 16:05:40 volumio sudo[24300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:40 volumio sudo[24300]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:40 volumio sudo[24302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 02 16:05:40 volumio sudo[24302]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:05:40 volumio sudo[24302]: pam_unix(sudo:session): session closed for user root Apr 02 16:05:40 volumio volumio[954]: info: CoreCommandRouter::volumioGetState Apr 02 16:05:40 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:40 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 02 16:05:40 volumio volumio[954]: info: Listing playlists Apr 02 16:05:40 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 02 16:05:40 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:05:40 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:05:40 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 16:05:42 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 16:05:43 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 16:05:44 volumio volumio[954]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 16:05:44 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 16:05:44 volumio volumio[954]: info: CoreCommandRouter::volumioGetQueue Apr 02 16:05:44 volumio volumio[954]: info: CoreStateMachine::getQueue Apr 02 16:05:44 volumio volumio[954]: info: CorePlayQueue::getQueue Apr 02 16:05:45 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 02 16:05:47 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::ClearQueue Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:05:51 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::clearPlayQueue Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::addQueueItems Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::addQueueItems Apr 02 16:05:51 volumio volumio[954]: info: Adding Item to queue: https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/; Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:05:51 volumio volumio[954]: info: CoreCommandRouter::volumioPlay Apr 02 16:05:51 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::play index 0 Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:05:51 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::play index undefined Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:51 volumio volumio[954]: info: CoreStateMachine::startPlaybackTimer Apr 02 16:05:51 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:51 volumio volumio[954]: info: [1775145951392] ControllerWebradio::clearAddPlayTrack Apr 02 16:05:51 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:05:51 volumio volumio[954]: info: sendMpdCommand stop took 14 milliseconds Apr 02 16:05:51 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 16:05:51 volumio volumio[954]: info: Apr 02 16:05:51 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:05:51 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:05:51 volumio volumio[954]: info: sendMpdCommand clear took 2 milliseconds Apr 02 16:05:51 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand load "https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/;" Apr 02 16:05:51 volumio volumio[954]: error: updateQueue error: null Apr 02 16:05:51 volumio volumio[954]: info: ------------------------------ 5ms Apr 02 16:05:53 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand add "https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/;" Apr 02 16:05:53 volumio volumio[954]: info: sendMpdCommand add "https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/;" took 2 milliseconds Apr 02 16:05:53 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 16:05:53 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand play Apr 02 16:05:53 volumio volumio[954]: info: Apr 02 16:05:53 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:05:53 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:05:53 volumio volumio[954]: info: sendMpdCommand play took 18 milliseconds Apr 02 16:05:53 volumio volumio[954]: info: ------------------------------ 5ms Apr 02 16:05:55 volumio volumio[954]: info: Apr 02 16:05:55 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:05:55 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:05:55 volumio volumio[954]: info: Apr 02 16:05:55 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:05:55 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:05:55 volumio volumio[954]: info: Apr 02 16:05:55 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:05:55 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:05:55 volumio volumio[954]: info: Apr 02 16:05:55 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:05:55 volumio volumio[954]: info: sendMpdCommand status took 10 milliseconds Apr 02 16:05:55 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:05:55 volumio volumio[954]: info: sendMpdCommand status took 22 milliseconds Apr 02 16:05:55 volumio volumio[954]: info: ------------------------------ 18ms Apr 02 16:05:55 volumio volumio[954]: info: sendMpdCommand status took 14 milliseconds Apr 02 16:05:55 volumio volumio[954]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:05:55 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:05:55 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:55 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":368,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Joe Lockwood - Hey You","artist":"Radio Italo4you","album":null,"uri":"https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/;","trackType":"pl:8018/;"} Apr 02 16:05:55 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus stop Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:05:55 volumio volumio[954]: info: ------------------------------ 74ms Apr 02 16:05:55 volumio volumio[954]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 02 16:05:55 volumio volumio[954]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:05:55 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:05:55 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:05:55 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:55 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":438,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Joe Lockwood - Hey You","artist":"Radio Italo4you","album":null,"uri":"https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/;","trackType":"pl:8018/;"} Apr 02 16:05:55 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus play Apr 02 16:05:55 volumio volumio[954]: info: Received an update from plugin. extracting info from payload Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:05:55 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:05:55 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:05:55 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":507,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Joe Lockwood - Hey You","artist":"Radio Italo4you","album":null,"uri":"https://thenonstopradio.com/play?url=http://s0.radiohost.pl:8018/;","trackType":"pl:8018/;"} Apr 02 16:05:55 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus play Apr 02 16:05:55 volumio volumio[954]: info: Received an update from plugin. extracting info from payload Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:05:55 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:05:55 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:05:55 volumio volumio[954]: info: ------------------------------ 100ms Apr 02 16:05:55 volumio volumio[954]: info: ------------------------------ 95ms Apr 02 16:05:58 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 16:05:58 volumio volumio[954]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 02 16:05:58 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 02 16:05:58 volumio volumio[954]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Apr 02 16:05:58 volumio volumio[954]: info: Received Get System Version Apr 02 16:05:58 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::ClearQueue Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:04 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::stPlaybackTimer Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::serviceStop Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::serviceStop Apr 02 16:06:04 volumio volumio[954]: info: [1775145964610] ControllerWebradio::stop Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::clearPlayQueue Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::addQueueItems Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::addQueueItems Apr 02 16:06:04 volumio volumio[954]: info: Adding Item to queue: https://radio.voltagefm.ru:9009/VOLTAGEFM Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:04 volumio volumio[954]: info: CoreCommandRouter::volumioPlay Apr 02 16:06:04 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::play index 0 Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:04 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::play index undefined Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:04 volumio volumio[954]: info: CoreStateMachine::startPlaybackTimer Apr 02 16:06:04 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:04 volumio volumio[954]: info: [1775145964665] ControllerWebradio::clearAddPlayTrack Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:04 volumio volumio[954]: info: Apr 02 16:06:04 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:04 volumio volumio[954]: info: sendMpdCommand stop took 91 milliseconds Apr 02 16:06:04 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:04 volumio volumio[954]: info: sendMpdCommand stop took 40 milliseconds Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 16:06:04 volumio volumio[954]: info: Apr 02 16:06:04 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:04 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:04 volumio volumio[954]: info: sendMpdCommand status took 11 milliseconds Apr 02 16:06:04 volumio volumio[954]: info: sendMpdCommand clear took 10 milliseconds Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:04 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand load "https://radio.voltagefm.ru:9009/VOLTAGEFM" Apr 02 16:06:04 volumio volumio[954]: error: updateQueue error: null Apr 02 16:06:04 volumio volumio[954]: info: ------------------------------ 19ms Apr 02 16:06:05 volumio volumio[954]: info: sendMpdCommand playlistinfo took 1244 milliseconds Apr 02 16:06:05 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand add "https://radio.voltagefm.ru:9009/VOLTAGEFM" Apr 02 16:06:05 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:05 volumio volumio[954]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 16:06:05 volumio volumio[954]: info: ------------------------------ 1270ms Apr 02 16:06:05 volumio volumio[954]: info: Apr 02 16:06:05 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:05 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:05 volumio volumio[954]: info: sendMpdCommand add "https://radio.voltagefm.ru:9009/VOLTAGEFM" took 8 milliseconds Apr 02 16:06:05 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 16:06:05 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand play Apr 02 16:06:05 volumio volumio[954]: info: ------------------------------ 3ms Apr 02 16:06:05 volumio volumio[954]: info: sendMpdCommand play took 2 milliseconds Apr 02 16:06:07 volumio volumio[954]: info: Apr 02 16:06:07 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:07 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:07 volumio volumio[954]: info: Apr 02 16:06:07 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:07 volumio volumio[954]: info: sendMpdCommand status took 9 milliseconds Apr 02 16:06:07 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:07 volumio volumio[954]: info: Apr 02 16:06:07 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:07 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:07 volumio volumio[954]: info: sendMpdCommand status took 11 milliseconds Apr 02 16:06:07 volumio volumio[954]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:07 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:07 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:07 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"110 Kbps","isStreaming":false,"title":"VOLTAGEFM","artist":"VOLTAGE FM","album":null,"uri":"https://radio.voltagefm.ru:9009/VOLTAGEFM","trackType":""} Apr 02 16:06:07 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus stop Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:07 volumio volumio[954]: info: ------------------------------ 46ms Apr 02 16:06:07 volumio volumio[954]: info: Apr 02 16:06:07 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:07 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:07 volumio volumio[954]: info: Apr 02 16:06:07 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:07 volumio volumio[954]: info: ------------------------------ 26ms Apr 02 16:06:07 volumio volumio[954]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 02 16:06:07 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:07 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:07 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:07 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"119 Kbps","isStreaming":false,"title":"VOLTAGEFM","artist":"VOLTAGE FM","album":null,"uri":"https://radio.voltagefm.ru:9009/VOLTAGEFM","trackType":""} Apr 02 16:06:07 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus play Apr 02 16:06:07 volumio volumio[954]: info: Received an update from plugin. extracting info from payload Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:07 volumio volumio[954]: info: ------------------------------ 80ms Apr 02 16:06:07 volumio volumio[954]: info: ------------------------------ 59ms Apr 02 16:06:07 volumio volumio[954]: info: sendMpdCommand status took 58 milliseconds Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:07 volumio volumio[954]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 16:06:07 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:07 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:07 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:07 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"114 Kbps","isStreaming":false,"title":"Klaas - One Two Step","artist":"VOLTAGE FM","album":null,"uri":"https://radio.voltagefm.ru:9009/VOLTAGEFM","trackType":""} Apr 02 16:06:07 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus play Apr 02 16:06:07 volumio volumio[954]: info: Received an update from plugin. extracting info from payload Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:07 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:07 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:07 volumio volumio[954]: info: ------------------------------ 92ms Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::ClearQueue Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:14 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::stPlaybackTimer Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:14 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:14 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::serviceStop Apr 02 16:06:14 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::serviceStop Apr 02 16:06:14 volumio volumio[954]: info: [1775145974935] ControllerWebradio::stop Apr 02 16:06:14 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:14 volumio volumio[954]: info: CorePlayQueue::clearPlayQueue Apr 02 16:06:14 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:14 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:14 volumio volumio[954]: info: CoreStateMachine::addQueueItems Apr 02 16:06:14 volumio volumio[954]: info: CorePlayQueue::addQueueItems Apr 02 16:06:14 volumio volumio[954]: info: Adding Item to queue: https://srv02.gpmradio.ru:8443/stream/personal/aacp/64/946442?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJrZXkiOiJjNmEyOWYyOTdlZmVjNGI1NmI1MTZmZDkxMTM1N2FkYyIsIklQIjoiOTUuMjYuNzkuNTkiLCJVQSI6Ik1vemlsbGEvNS4wIChXaW5kb3dzIE5UIDEwLjA7IFdpbjY0OyB4NjQpIEFwcGxlV2ViS2l0LzUzNy4zNiAoS0hUTUwsIGxpa2UgR2Vja28pIENocm9tZS8xMDkuMC4wLjAgU2FmYXJpLzUzNy4zNiIsIlJlZiI6Imh0dHBzOi8vMTAxLnJ1LyIsInVpZF9jaGFubmVsIjoiOTQ2NDQyIiwidHlwZV9jaGFubmVsIjoicGVyc29uYWwiLCJ0eXBlRGV2aWNlIjoiUEMiLCJCcm93c2VyIjoiQ2hyb21lIiwiQnJvd3NlclZlcnNpb24iOiIxMDkuMC4wLjAiLCJTeXN0ZW0iOiJXaW5kb3dzIDEwIiwiZXhwIjoxNzQ3NDE5NjI0fQ.Ar3_Uh-yRQV5IjtlHM70car7g1m91FJUFiLOuvEWHT0 Apr 02 16:06:15 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 16:06:15 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:15 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:15 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:15 volumio volumio[954]: info: CoreCommandRouter::volumioPlay Apr 02 16:06:15 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::play index 0 Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:15 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::play index undefined Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:15 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::startPlaybackTimer Apr 02 16:06:15 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:15 volumio volumio[954]: info: [1775145974966] ControllerWebradio::clearAddPlayTrack Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:15 volumio volumio[954]: info: Apr 02 16:06:15 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand stop took 57 milliseconds Apr 02 16:06:15 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand stop took 27 milliseconds Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 16:06:15 volumio volumio[954]: info: Apr 02 16:06:15 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:15 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand status took 13 milliseconds Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand clear took 8 milliseconds Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand load "https://srv02.gpmradio.ru:8443/stream/personal/aacp/64/946442?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJrZXkiOiJjNmEyOWYyOTdlZmVjNGI1NmI1MTZmZDkxMTM1N2FkYyIsIklQIjoiOTUuMjYuNzkuNTkiLCJVQSI6Ik1vemlsbGEvNS4wIChXaW5kb3dzIE5UIDEwLjA7IFdpbjY0OyB4NjQpIEFwcGxlV2ViS2l0LzUzNy4zNiAoS0hUTUwsIGxpa2UgR2Vja28pIENocm9tZS8xMDkuMC4wLjAgU2FmYXJpLzUzNy4zNiIsIlJlZiI6Imh0dHBzOi8vMTAxLnJ1LyIsInVpZF9jaGFubmVsIjoiOTQ2NDQyIiwidHlwZV9jaGFubmVsIjoicGVyc29uYWwiLCJ0eXBlRGV2aWNlIjoiUEMiLCJCcm93c2VyIjoiQ2hyb21lIiwiQnJvd3NlclZlcnNpb24iOiIxMDkuMC4wLjAiLCJTeXN0ZW0iOiJXaW5kb3dzIDEwIiwiZXhwIjoxNzQ3NDE5NjI0fQ.Ar3_Uh-yRQV5IjtlHM70car7g1m91FJUFiLOuvEWHT0" Apr 02 16:06:15 volumio volumio[954]: error: updateQueue error: null Apr 02 16:06:15 volumio volumio[954]: info: ------------------------------ 12ms Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:15 volumio volumio[954]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 16:06:15 volumio volumio[954]: info: ------------------------------ 31ms Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand add "https://srv02.gpmradio.ru:8443/stream/personal/aacp/64/946442?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJrZXkiOiJjNmEyOWYyOTdlZmVjNGI1NmI1MTZmZDkxMTM1N2FkYyIsIklQIjoiOTUuMjYuNzkuNTkiLCJVQSI6Ik1vemlsbGEvNS4wIChXaW5kb3dzIE5UIDEwLjA7IFdpbjY0OyB4NjQpIEFwcGxlV2ViS2l0LzUzNy4zNiAoS0hUTUwsIGxpa2UgR2Vja28pIENocm9tZS8xMDkuMC4wLjAgU2FmYXJpLzUzNy4zNiIsIlJlZiI6Imh0dHBzOi8vMTAxLnJ1LyIsInVpZF9jaGFubmVsIjoiOTQ2NDQyIiwidHlwZV9jaGFubmVsIjoicGVyc29uYWwiLCJ0eXBlRGV2aWNlIjoiUEMiLCJCcm93c2VyIjoiQ2hyb21lIiwiQnJvd3NlclZlcnNpb24iOiIxMDkuMC4wLjAiLCJTeXN0ZW0iOiJXaW5kb3dzIDEwIiwiZXhwIjoxNzQ3NDE5NjI0fQ.Ar3_Uh-yRQV5IjtlHM70car7g1m91FJUFiLOuvEWHT0" Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand add "https://srv02.gpmradio.ru:8443/stream/personal/aacp/64/946442?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJrZXkiOiJjNmEyOWYyOTdlZmVjNGI1NmI1MTZmZDkxMTM1N2FkYyIsIklQIjoiOTUuMjYuNzkuNTkiLCJVQSI6Ik1vemlsbGEvNS4wIChXaW5kb3dzIE5UIDEwLjA7IFdpbjY0OyB4NjQpIEFwcGxlV2ViS2l0LzUzNy4zNiAoS0hUTUwsIGxpa2UgR2Vja28pIENocm9tZS8xMDkuMC4wLjAgU2FmYXJpLzUzNy4zNiIsIlJlZiI6Imh0dHBzOi8vMTAxLnJ1LyIsInVpZF9jaGFubmVsIjoiOTQ2NDQyIiwidHlwZV9jaGFubmVsIjoicGVyc29uYWwiLCJ0eXBlRGV2aWNlIjoiUEMiLCJCcm93c2VyIjoiQ2hyb21lIiwiQnJvd3NlclZlcnNpb24iOiIxMDkuMC4wLjAiLCJTeXN0ZW0iOiJXaW5kb3dzIDEwIiwiZXhwIjoxNzQ3NDE5NjI0fQ.Ar3_Uh-yRQV5IjtlHM70car7g1m91FJUFiLOuvEWHT0" took 9 milliseconds Apr 02 16:06:15 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 16:06:15 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand play Apr 02 16:06:15 volumio volumio[954]: info: Apr 02 16:06:15 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:15 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:15 volumio volumio[954]: info: sendMpdCommand play took 16 milliseconds Apr 02 16:06:15 volumio volumio[954]: info: ------------------------------ 2ms Apr 02 16:06:16 volumio volumio[954]: info: Apr 02 16:06:16 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:16 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:16 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:16 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 16:06:16 volumio volumio[954]: info: sendMpdCommand status took 8 milliseconds Apr 02 16:06:16 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:16 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:16 volumio volumio[954]: info: sendMpdCommand clearerror took 3 milliseconds Apr 02 16:06:16 volumio volumio[954]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 16:06:16 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:16 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:16 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:16 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:16 volumio volumio[954]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"946442?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJrZXkiOiJjNmEyOWYyOTdlZmVjNGI1NmI1MTZmZDkxMTM1N2FkYyIsIklQIjoiOTUuMjYuNzkuNTkiLCJVQSI6Ik1vemlsbGEvNS4wIChXaW5kb3dzIE5UIDEwLjA7IFdpbjY0OyB4NjQpIEFwcGxlV2ViS2l0LzUzNy4zNiAoS0hUTUwsIGxpa2UgR2Vja28pIENocm9tZS8xMDkuMC4wLjAgU2FmYXJpLzUzNy4zNiIsIlJlZiI6Imh0dHBzOi8vMTAxLnJ1LyIsInVpZF9jaGFubmVsIjoiOTQ2NDQyIiwidHlwZV9jaGFubmVsIjoicGVyc29uYWwiLCJ0eXBlRGV2aWNlIjoiUEMiLCJCcm93c2VyIjoiQ2hyb21lIiwiQnJvd3NlclZlcnNpb24iOiIxMDkuMC4wLjAiLCJTeXN0ZW0iOiJXaW5kb3dzIDEwIiwiZXhwIjoxNzQ3NDE5NjI0fQ.Ar3_Uh-yRQV5IjtlHM70car7g1m91FJUFiLOuvEWHT0","artist":null,"album":null,"uri":"https://srv02.gpmradio.ru:8443/stream/personal/aacp/64/946442?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJrZXkiOiJjNmEyOWYyOTdlZmVjNGI1NmI1MTZmZDkxMTM1N2FkYyIsIklQIjoiOTUuMjYuNzkuNTkiLCJVQSI6Ik1vemlsbGEvNS4wIChXaW5kb3dzIE5UIDEwLjA7IFdpbjY0OyB4NjQpIEFwcGxlV2ViS2l0LzUzNy4zNiAoS0hUTUwsIGxpa2UgR2Vja28pIENocm9tZS8xMDkuMC4wLjAgU2FmYXJpLzUzNy4zNiIsIlJlZiI6Imh0dHBzOi8vMTAxLnJ1LyIsInVpZF9jaGFubmVsIjoiOTQ2NDQyIiwidHlwZV9jaGFubmVsIjoicGVyc29uYWwiLCJ0eXBlRGV2aWNlIjoiUEMiLCJCcm93c2VyIjoiQ2hyb21lIiwiQnJvd3NlclZlcnNpb24iOiIxMDkuMC4wLjAiLCJTeXN0ZW0iOiJXaW5kb3dzIDEwIiwiZXhwIjoxNzQ3NDE5NjI0fQ.Ar3_Uh-yRQV5IjtlHM70car7g1m91FJUFiLOuvEWHT0","trackType":""} Apr 02 16:06:16 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:16 volumio volumio[954]: info: CoreStateMachine::syncState stateService stop Apr 02 16:06:16 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus stop Apr 02 16:06:16 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:16 volumio volumio[954]: info: No code Apr 02 16:06:16 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:16 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:16 volumio volumio[954]: info: ------------------------------ 74ms Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::ClearQueue Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:19 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::clearPlayQueue Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::addQueueItems Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::addQueueItems Apr 02 16:06:19 volumio volumio[954]: info: Adding Item to queue: https://radio.voltagefm.ru:9009/VOLTAGEFM Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:19 volumio volumio[954]: info: CoreCommandRouter::volumioPlay Apr 02 16:06:19 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::play index 0 Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:19 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::play index undefined Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:19 volumio volumio[954]: info: CoreStateMachine::startPlaybackTimer Apr 02 16:06:19 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:19 volumio volumio[954]: info: [1775145979699] ControllerWebradio::clearAddPlayTrack Apr 02 16:06:19 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:19 volumio volumio[954]: info: sendMpdCommand stop took 11 milliseconds Apr 02 16:06:19 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 16:06:19 volumio volumio[954]: info: Apr 02 16:06:19 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:19 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:19 volumio volumio[954]: info: sendMpdCommand clear took 4 milliseconds Apr 02 16:06:19 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand load "https://radio.voltagefm.ru:9009/VOLTAGEFM" Apr 02 16:06:19 volumio volumio[954]: error: updateQueue error: null Apr 02 16:06:19 volumio volumio[954]: info: ------------------------------ 3ms Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::ClearQueue Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:20 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::clearPlayQueue Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::addQueueItems Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::addQueueItems Apr 02 16:06:20 volumio volumio[954]: info: Adding Item to queue: https://pub0302.101.ru:8000/stream/pro/aac/64/144? Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:20 volumio volumio[954]: info: CoreCommandRouter::volumioPlay Apr 02 16:06:20 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::play index 0 Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:20 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::play index undefined Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:20 volumio volumio[954]: info: CoreStateMachine::startPlaybackTimer Apr 02 16:06:20 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:20 volumio volumio[954]: info: [1775145980913] ControllerWebradio::clearAddPlayTrack Apr 02 16:06:20 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:21 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand add "https://radio.voltagefm.ru:9009/VOLTAGEFM" Apr 02 16:06:21 volumio volumio[954]: info: sendMpdCommand stop took 296 milliseconds Apr 02 16:06:21 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 16:06:21 volumio volumio[954]: info: Apr 02 16:06:21 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:21 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:21 volumio volumio[954]: info: sendMpdCommand add "https://radio.voltagefm.ru:9009/VOLTAGEFM" took 10 milliseconds Apr 02 16:06:21 volumio volumio[954]: info: sendMpdCommand clear took 7 milliseconds Apr 02 16:06:21 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 16:06:21 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand play Apr 02 16:06:21 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand load "https://pub0302.101.ru:8000/stream/pro/aac/64/144?" Apr 02 16:06:22 volumio volumio[954]: error: updateQueue error: null Apr 02 16:06:22 volumio volumio[954]: info: ------------------------------ 1013ms Apr 02 16:06:22 volumio volumio[954]: info: sendMpdCommand play took 1013 milliseconds Apr 02 16:06:22 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand add "https://pub0302.101.ru:8000/stream/pro/aac/64/144?" Apr 02 16:06:22 volumio volumio[954]: info: Apr 02 16:06:22 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:22 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:22 volumio volumio[954]: info: sendMpdCommand add "https://pub0302.101.ru:8000/stream/pro/aac/64/144?" took 6 milliseconds Apr 02 16:06:22 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 16:06:22 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand play Apr 02 16:06:22 volumio volumio[954]: info: ------------------------------ 4ms Apr 02 16:06:22 volumio volumio[954]: info: sendMpdCommand play took 2 milliseconds Apr 02 16:06:23 volumio volumio[954]: info: Apr 02 16:06:23 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:23 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:23 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:23 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 02 16:06:23 volumio volumio[954]: info: sendMpdCommand status took 10 milliseconds Apr 02 16:06:23 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:23 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:23 volumio volumio[954]: info: sendMpdCommand clearerror took 9 milliseconds Apr 02 16:06:23 volumio volumio[954]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 02 16:06:23 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:23 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:23 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:23 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:23 volumio volumio[954]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"144?","artist":null,"album":null,"uri":"https://pub0302.101.ru:8000/stream/pro/aac/64/144?","trackType":""} Apr 02 16:06:23 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:23 volumio volumio[954]: info: CoreStateMachine::syncState stateService stop Apr 02 16:06:23 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus stop Apr 02 16:06:23 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:23 volumio volumio[954]: info: No code Apr 02 16:06:23 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:23 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:23 volumio volumio[954]: info: ------------------------------ 73ms Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::ClearQueue Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:25 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::clearPlayQueue Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::addQueueItems Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::addQueueItems Apr 02 16:06:25 volumio volumio[954]: info: Adding Item to queue: https://radiolegenda.ru:8001/stream.ogg Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::saveQueue Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::volumioPushQueue Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::updateTrackBlock Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::getTrackBlock Apr 02 16:06:25 volumio volumio[954]: info: CoreCommandRouter::volumioPlay Apr 02 16:06:25 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::play index 0 Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::stop Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:25 volumio volumio[954]: verbose: UNSET VOLATILE Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::play index undefined Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:25 volumio volumio[954]: info: CoreStateMachine::startPlaybackTimer Apr 02 16:06:25 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:25 volumio volumio[954]: info: [1775145985389] ControllerWebradio::clearAddPlayTrack Apr 02 16:06:25 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 16:06:25 volumio volumio[954]: info: sendMpdCommand stop took 33 milliseconds Apr 02 16:06:25 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 16:06:25 volumio volumio[954]: info: Apr 02 16:06:25 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:25 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:25 volumio volumio[954]: info: sendMpdCommand clear took 15 milliseconds Apr 02 16:06:25 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand load "https://radiolegenda.ru:8001/stream.ogg" Apr 02 16:06:25 volumio volumio[954]: error: updateQueue error: null Apr 02 16:06:25 volumio volumio[954]: info: ------------------------------ 10ms Apr 02 16:06:26 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand add "https://radiolegenda.ru:8001/stream.ogg" Apr 02 16:06:26 volumio volumio[954]: info: Apr 02 16:06:26 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:26 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:26 volumio volumio[954]: info: sendMpdCommand add "https://radiolegenda.ru:8001/stream.ogg" took 2 milliseconds Apr 02 16:06:26 volumio volumio[954]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 02 16:06:26 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand play Apr 02 16:06:26 volumio volumio[954]: info: ------------------------------ 3ms Apr 02 16:06:26 volumio volumio[954]: info: sendMpdCommand play took 2 milliseconds Apr 02 16:06:27 volumio volumio[954]: info: Apr 02 16:06:27 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:27 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:27 volumio volumio[954]: info: Apr 02 16:06:27 volumio volumio[954]: ---------------------------- MPD announces state update: player Apr 02 16:06:27 volumio volumio[954]: info: sendMpdCommand status took 30 milliseconds Apr 02 16:06:27 volumio volumio[954]: info: ControllerMpd::getState Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand status Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:27 volumio volumio[954]: info: Apr 02 16:06:27 volumio volumio[954]: ---------------------------- MPD announces system playlist update Apr 02 16:06:27 volumio volumio[954]: info: Ignoring MPD Status Update Apr 02 16:06:27 volumio volumio[954]: info: sendMpdCommand status took 13 milliseconds Apr 02 16:06:27 volumio volumio[954]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::parseState Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:27 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:27 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:27 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.ogg","artist":"LEGENDA","album":null,"uri":"https://radiolegenda.ru:8001/stream.ogg","trackType":"ogg"} Apr 02 16:06:27 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus stop Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:27 volumio volumio[954]: info: ------------------------------ 80ms Apr 02 16:06:27 volumio volumio[954]: info: ------------------------------ 37ms Apr 02 16:06:27 volumio volumio[954]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 02 16:06:27 volumio volumio[954]: verbose: ControllerMpd::parseTrackInfo Apr 02 16:06:27 volumio volumio[954]: info: ControllerMpd::pushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::servicePushState Apr 02 16:06:27 volumio volumio[954]: info: CorePlayQueue::getTrack 0 Apr 02 16:06:27 volumio volumio[954]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848721718,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.ogg","artist":"LEGENDA","album":null,"uri":"https://radiolegenda.ru:8001/stream.ogg","trackType":"ogg"} Apr 02 16:06:27 volumio volumio[954]: verbose: CURRENT POSITION 0 Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::syncState stateService play Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::syncState currentStatus play Apr 02 16:06:27 volumio volumio[954]: info: Received an update from plugin. extracting info from payload Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:27 volumio volumio[954]: info: CoreStateMachine::pushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::volumioPushState Apr 02 16:06:27 volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 16:06:27 volumio volumio[954]: info: ------------------------------ 106ms Apr 02 16:06:28 volumio volumio[954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 16:06:28 volumio volumio[954]: TypeError: Cannot read property 'slice' of null Apr 02 16:06:28 volumio volumio[954]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) Apr 02 16:06:28 volumio volumio[954]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) Apr 02 16:06:28 volumio volumio[954]: at Object.onceWrapper (events.js:315:30) Apr 02 16:06:28 volumio volumio[954]: at emitOne (events.js:116:13) Apr 02 16:06:28 volumio volumio[954]: at ClientRequest.emit (events.js:211:7) Apr 02 16:06:28 volumio volumio[954]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Apr 02 16:06:28 volumio volumio[954]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Apr 02 16:06:28 volumio volumio[954]: at TLSSocket.socketOnData (_http_client.js:440:20) Apr 02 16:06:28 volumio volumio[954]: at emitOne (events.js:116:13) Apr 02 16:06:28 volumio volumio[954]: at TLSSocket.emit (events.js:211:7) Apr 02 16:06:28 volumio volumio[954]: at addChunk (_stream_readable.js:263:12) Apr 02 16:06:28 volumio volumio[954]: at readableAddChunk (_stream_readable.js:250:11) Apr 02 16:06:28 volumio volumio[954]: at TLSSocket.Readable.push (_stream_readable.js:208:10) Apr 02 16:06:28 volumio volumio[954]: at TLSWrap.onread (net.js:607:20) Apr 02 16:06:28 volumio volumio[954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 16:06:29 volumio sudo[24419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 16:05 Apr 02 16:06:29 volumio sudo[24419]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"