-- Logs begin at Sun 2024-04-28 09:39:10 UTC, end at Tue 2024-04-30 06:36:26 UTC. -- Apr 30 06:35:02 volumio volumio[1142]: info: Clearing queue after UPNP request Apr 30 06:35:02 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:02 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:02 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:02 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:02 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:02 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:02 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:02 volumio volumio[1142]: info: Apr 30 06:35:02 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:02 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:02 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:02 volumio volumio[1142]: info: ------------------------------ 50ms Apr 30 06:35:03 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 10 seconds. Apr 30 06:35:03 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:03 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:03 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:03 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:03 volumio volumio[1142]: info: Apr 30 06:35:03 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:03 volumio volumio[1142]: info: sendMpdCommand status took 51 milliseconds Apr 30 06:35:03 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 7ms Apr 30 06:35:03 volumio volumio[1142]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:35:03 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:03 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:03 volumio volumio[1142]: verbose: In UPNP mode Apr 30 06:35:03 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"734 Kbps","isStreaming":false,"title":"Have I Told You Lately (That I Love You)","artist":"Rod Stewart","album":"The Very Best of MTV Unplugged, Volume 2","uri":"http://192.168.1.102:41399/76bdbe8a1ca9c64b98d19a1348e1a0b0/primary%3ADownload%2FTopping%2FVarious%20-%20The%20Very%20Best%20Of%20MTV%20Unplugged.%20Vol.%201-2%20(2002-2003)%2F2003%20-%20Vol.%202%2F08%20(Rod%20Stewart)%20Have%20I%20Told%20You%20Lately%20(That%20I%20Love%20You).flac","trackType":"flac"} Apr 30 06:35:03 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 64ms Apr 30 06:35:03 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 06:35:03 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:03 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:03 volumio volumio[1142]: verbose: In UPNP mode Apr 30 06:35:03 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"660 Kbps","isStreaming":false,"title":"Have I Told You Lately (That I Love You)","artist":"Rod Stewart","album":"The Very Best of MTV Unplugged, Volume 2","uri":"http://192.168.1.102:41399/76bdbe8a1ca9c64b98d19a1348e1a0b0/primary%3ADownload%2FTopping%2FVarious%20-%20The%20Very%20Best%20Of%20MTV%20Unplugged.%20Vol.%201-2%20(2002-2003)%2F2003%20-%20Vol.%202%2F08%20(Rod%20Stewart)%20Have%20I%20Told%20You%20Lately%20(That%20I%20Love%20You).flac","trackType":"flac"} Apr 30 06:35:03 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:35:03 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:03 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:03 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:03 volumio volumio[1142]: info: ------------------------------ 95ms Apr 30 06:35:04 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:04 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:04 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:04 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:04 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:04 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:04 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 30 06:35:04 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:04 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:04 volumio volumio[1142]: info: Consume mode Apr 30 06:35:04 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:04 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 4ms Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 4ms Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:04 volumio volumio[1142]: info: Apr 30 06:35:04 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:04 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:04 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:04 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:04 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:04 volumio volumio[1142]: info: Consume mode Apr 30 06:35:04 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:04 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:05 volumio volumio[1142]: info: Apr 30 06:35:05 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:05 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:05 volumio volumio[1142]: info: ------------------------------ 4ms Apr 30 06:35:05 volumio volumio[1142]: info: Apr 30 06:35:05 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:05 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:05 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:05 volumio volumio[1142]: info: Apr 30 06:35:05 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:05 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:05 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:05 volumio volumio[1142]: info: Apr 30 06:35:05 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:05 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:05 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:05 volumio volumio[1142]: info: Apr 30 06:35:05 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:05 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:05 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:05 volumio volumio[1142]: info: Apr 30 06:35:05 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:05 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:05 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:07 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 16 Apr 30 06:35:07 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:10 volumio volumio[1142]: info: CoreCommandRouter::volumioPause Apr 30 06:35:10 volumio volumio[1142]: info: CoreStateMachine::pause Apr 30 06:35:10 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:10 volumio volumio[1142]: info: CoreStateMachine::servicePause Apr 30 06:35:10 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:10 volumio volumio[1142]: info: Error: no service or no trackblock to pause Apr 30 06:35:11 volumio volumio[1142]: info: CoreCommandRouter::volumioPause Apr 30 06:35:11 volumio volumio[1142]: info: CoreStateMachine::pause Apr 30 06:35:22 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Apr 30 06:35:22 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:25 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::serviceStop Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: ControllerMpd::stop Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Apr 30 06:35:25 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:25 volumio volumio[1142]: info: Adding Item to queue: webmer/0 Apr 30 06:35:25 volumio volumio[1142]: info: Exploding uri webmer/0 in service motherearthradio Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::play index 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::play index undefined Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:25 volumio volumio[1142]: info: Apr 30 06:35:25 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand stop took 76 milliseconds Apr 30 06:35:25 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand stop took 26 milliseconds Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:35:25 volumio volumio[1142]: info: Apr 30 06:35:25 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:25 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand clear took 10 milliseconds Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 30 06:35:25 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:25 volumio volumio[1142]: info: Apr 30 06:35:25 volumio volumio[1142]: ---------------------------- MPD announces state update: options Apr 30 06:35:25 volumio volumio[1142]: info: ------------------------------ 56ms Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 55 milliseconds Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand consume 1 took 55 milliseconds Apr 30 06:35:25 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:25 volumio volumio[1142]: info: [1714458925252] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth/motherearth Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" Apr 30 06:35:25 volumio volumio[1142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:35:25 volumio volumio[1142]: info: ------------------------------ 82ms Apr 30 06:35:25 volumio volumio[1142]: info: Apr 30 06:35:25 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:25 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand status took 22 milliseconds Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" took 18 milliseconds Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:35:25 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:25 volumio volumio[1142]: info: ------------------------------ 93ms Apr 30 06:35:25 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 10 seconds. Apr 30 06:35:25 volumio volumio[1142]: info: ------------------------------ 71ms Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand play took 49 milliseconds Apr 30 06:35:25 volumio volumio[1142]: info: Clearing queue after UPNP request Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:25 volumio volumio[1142]: info: Apr 30 06:35:25 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:25 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:25 volumio volumio[1142]: info: Apr 30 06:35:25 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:25 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:25 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:25 volumio volumio[1142]: info: ------------------------------ 8ms Apr 30 06:35:25 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:35:25 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:25 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 30 06:35:25 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:25 volumio volumio[1142]: info: No code Apr 30 06:35:25 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:25 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:25 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:25 volumio volumio[1142]: info: ------------------------------ 85ms Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces state update: options Apr 30 06:35:26 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:26 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:26 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:26 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:26 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:26 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 30 06:35:26 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:26 volumio volumio[1142]: info: No code Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 32ms Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:26 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:26 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:26 volumio volumio[1142]: info: Apr 30 06:35:26 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:26 volumio volumio[1142]: info: sendMpdCommand status took 52 milliseconds Apr 30 06:35:26 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 10ms Apr 30 06:35:26 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:35:26 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:26 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:26 volumio volumio[1142]: verbose: In UPNP mode Apr 30 06:35:26 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":509,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Like a Rolling Stone","artist":"Bob Dylan","album":"The Very Best of MTV Unplugged, Volume 2","uri":"http://192.168.1.102:41399/ba38d602f8f03f9a1cff39baf7e47800/primary%3ADownload%2FTopping%2FVarious%20-%20The%20Very%20Best%20Of%20MTV%20Unplugged.%20Vol.%201-2%20(2002-2003)%2F2003%20-%20Vol.%202%2F09%20(Bob%20Dylan)%20Like%20a%20Rolling%20Stone.flac","trackType":"flac"} Apr 30 06:35:26 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 65ms Apr 30 06:35:26 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 30 06:35:26 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:26 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:26 volumio volumio[1142]: verbose: In UPNP mode Apr 30 06:35:26 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":509,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"884 Kbps","isStreaming":false,"title":"Like a Rolling Stone","artist":"Bob Dylan","album":"The Very Best of MTV Unplugged, Volume 2","uri":"http://192.168.1.102:41399/ba38d602f8f03f9a1cff39baf7e47800/primary%3ADownload%2FTopping%2FVarious%20-%20The%20Very%20Best%20Of%20MTV%20Unplugged.%20Vol.%201-2%20(2002-2003)%2F2003%20-%20Vol.%202%2F09%20(Bob%20Dylan)%20Like%20a%20Rolling%20Stone.flac","trackType":"flac"} Apr 30 06:35:26 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:35:26 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:26 volumio volumio[1142]: info: ------------------------------ 50ms Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:26 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:26 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:27 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:27 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:27 volumio volumio[1142]: info: Consume mode Apr 30 06:35:27 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:27 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:27 volumio volumio[1142]: info: Apr 30 06:35:27 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:27 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:27 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:27 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:27 volumio volumio[1142]: info: Consume mode Apr 30 06:35:27 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:27 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:27 volumio volumio[1142]: info: ------------------------------ 9ms Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:28 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:28 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:28 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:28 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:28 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:28 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:28 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:28 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:28 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:28 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:28 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:28 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:28 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::serviceStop Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:28 volumio volumio[1142]: info: ControllerMpd::stop Apr 30 06:35:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Apr 30 06:35:28 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:28 volumio volumio[1142]: info: Adding Item to queue: webmer/1 Apr 30 06:35:28 volumio volumio[1142]: info: Exploding uri webmer/1 in service motherearthradio Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::play index 0 Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::play index undefined Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:28 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:35:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 06:35:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 06:35:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:28 volumio volumio[1142]: info: Apr 30 06:35:28 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:28 volumio volumio[1142]: info: sendMpdCommand stop took 139 milliseconds Apr 30 06:35:28 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:28 volumio volumio[1142]: info: sendMpdCommand stop took 30 milliseconds Apr 30 06:35:28 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:35:29 volumio volumio[1142]: info: Apr 30 06:35:29 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:29 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand status took 66 milliseconds Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand clear took 67 milliseconds Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 30 06:35:29 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:29 volumio volumio[1142]: info: Apr 30 06:35:29 volumio volumio[1142]: ---------------------------- MPD announces state update: options Apr 30 06:35:29 volumio volumio[1142]: info: ------------------------------ 17ms Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand consume 1 took 12 milliseconds Apr 30 06:35:29 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:29 volumio volumio[1142]: info: [1714458929042] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik" Apr 30 06:35:29 volumio volumio[1142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 06:35:29 volumio volumio[1142]: info: ------------------------------ 113ms Apr 30 06:35:29 volumio volumio[1142]: info: Apr 30 06:35:29 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:29 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand status took 34 milliseconds Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik" took 30 milliseconds Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:29 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:35:29 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:29 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:29 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:29 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:29 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:29 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:29 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:29 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:29 volumio volumio[1142]: info: ------------------------------ 76ms Apr 30 06:35:29 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 10 seconds. Apr 30 06:35:29 volumio volumio[1142]: info: ------------------------------ 54ms Apr 30 06:35:29 volumio volumio[1142]: info: sendMpdCommand play took 39 milliseconds Apr 30 06:35:30 volumio volumio[1142]: info: Apr 30 06:35:30 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:30 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:30 volumio volumio[1142]: info: sendMpdCommand status took 79 milliseconds Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:30 volumio volumio[1142]: info: Apr 30 06:35:30 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:30 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:30 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:30 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:30 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:30 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:30 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:30 volumio volumio[1142]: info: ------------------------------ 84ms Apr 30 06:35:30 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:30 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 06:35:30 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:30 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:30 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:30 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:30 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:30 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:30 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 16 Apr 30 06:35:30 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:30 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:31 volumio volumio[1142]: info: Apr 30 06:35:31 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:31 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:31 volumio volumio[1142]: info: Apr 30 06:35:31 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:31 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:31 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:31 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:31 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Apr 30 06:35:31 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:31 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:31 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 06:35:31 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:31 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:31 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:31 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:31 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:31 volumio volumio[1142]: info: ------------------------------ 9ms Apr 30 06:35:36 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Apr 30 06:35:36 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:36 volumio volumio[1142]: info: Adding Item to queue: webmer/0 Apr 30 06:35:36 volumio volumio[1142]: info: Using cached record of: webmer/0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::play index 0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::play index undefined Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand stop took 16 milliseconds Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:35:36 volumio volumio[1142]: info: Apr 30 06:35:36 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:36 volumio volumio[1142]: info: Apr 30 06:35:36 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:36 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:36 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand clear took 4 milliseconds Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 30 06:35:36 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:35:36 volumio volumio[1142]: info: ------------------------------ 7ms Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand consume 1 took 4 milliseconds Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:36 volumio volumio[1142]: info: [1714458936480] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth/motherearth Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" Apr 30 06:35:36 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:36 volumio volumio[1142]: info: ------------------------------ 28ms Apr 30 06:35:36 volumio volumio[1142]: info: Apr 30 06:35:36 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:36 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth/motherearth" took 22 milliseconds Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:35:36 volumio volumio[1142]: info: ------------------------------ 32ms Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand play took 24 milliseconds Apr 30 06:35:36 volumio volumio[1142]: info: Apr 30 06:35:36 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:36 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:36 volumio volumio[1142]: info: Apr 30 06:35:36 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand status took 19 milliseconds Apr 30 06:35:36 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:36 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:36 volumio volumio[1142]: info: ------------------------------ 25ms Apr 30 06:35:36 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 06:35:36 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:36 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:36 volumio volumio[1142]: info: ------------------------------ 7ms Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/c07725473918e810058633dc-1702145939.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"Fat Freddy's Drop - The Camel","title":"The Camel","artist":"Fat Freddy's Drop","album":"Live At Roundhouse London","streaming":true,"disableUiControls":true,"duration":565,"seek":0,"bitdepth":"24 bit","channels":2} Apr 30 06:35:36 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:36 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:36 volumio volumio[1142]: info: [1714458936811] [MotherEarth] setting new timer with duration of 565000 seconds. Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:36 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Apr 30 06:35:37 volumio volumio[1142]: info: Apr 30 06:35:37 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:37 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:37 volumio volumio[1142]: info: Apr 30 06:35:37 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:37 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:37 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:37 volumio volumio[1142]: info: ------------------------------ 7ms Apr 30 06:35:37 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Apr 30 06:35:37 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:37 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:37 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 06:35:37 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:37 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:37 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:37 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:37 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Apr 30 06:35:37 volumio volumio[1142]: info: ------------------------------ 13ms Apr 30 06:35:42 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Apr 30 06:35:42 volumio volumio[1142]: info: [1714458942641] [80s80s] handleBrowseUri curUri: 80s80s Apr 30 06:35:42 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:43 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Apr 30 06:35:43 volumio volumio[1142]: info: [1714458943769] [80s80s] handleBrowseUri curUri: 80s80s/eighties Apr 30 06:35:43 volumio volumio[1142]: info: [1714458943769] [80s80s] getRadioContent url: eighties Apr 30 06:35:43 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:44 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::serviceStop Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Apr 30 06:35:44 volumio volumio[1142]: info: ControllerMpd::stop Apr 30 06:35:44 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Apr 30 06:35:44 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:44 volumio volumio[1142]: info: Adding Item to queue: webeighties/2 Apr 30 06:35:44 volumio volumio[1142]: info: Using cached record of: webeighties/2 Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::play index 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::play index undefined Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 06:35:44 volumio volumio[1142]: info: [1714458944791] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Apr 30 06:35:44 volumio volumio[1142]: info: [1714458944791] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Apr 30 06:35:44 volumio volumio[1142]: info: Apr 30 06:35:44 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:44 volumio volumio[1142]: info: sendMpdCommand stop took 90 milliseconds Apr 30 06:35:44 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:44 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received motherearthradio Apr 30 06:35:44 volumio volumio[1142]: info: sendMpdCommand status took 59 milliseconds Apr 30 06:35:44 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:44 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:44 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 06:35:44 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:44 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:44 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:44 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:44 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:44 volumio volumio[1142]: info: ------------------------------ 96ms Apr 30 06:35:45 volumio volumio[1142]: info: [1714458945046] [80s80s] received new event containing 2 songs. Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand stop took 2 milliseconds Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:35:45 volumio volumio[1142]: info: Apr 30 06:35:45 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:45 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand clear took 4 milliseconds Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 30 06:35:45 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:45 volumio volumio[1142]: info: ------------------------------ 4ms Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand consume 1 took 2 milliseconds Apr 30 06:35:45 volumio volumio[1142]: info: [1714458945056] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Apr 30 06:35:45 volumio volumio[1142]: info: Apr 30 06:35:45 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:45 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 3 milliseconds Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:35:45 volumio volumio[1142]: info: ------------------------------ 14ms Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand play took 10 milliseconds Apr 30 06:35:45 volumio volumio[1142]: info: [1714458945080] [80s80s] Pushing the next song state: Chicago - Stay the Night Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/aa/01/c6/aa01c6ad-98b2-8b45-0749-50d6c61fb7a1/source/600x600bb.jpg","name":"Chicago - Stay the Night","title":"Stay the Night","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 30 06:35:45 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:45 volumio volumio[1142]: info: [1714458945082] [80s80s] PlayNextTrack API delay: 5 Apr 30 06:35:45 volumio volumio[1142]: info: [1714458945086] [80s80s] Setting timer to: 218000 milliseconds. Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Apr 30 06:35:45 volumio volumio[1142]: info: Clearing queue after UPNP request Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::serviceStop Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Apr 30 06:35:45 volumio volumio[1142]: info: ControllerMpd::stop Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:45 volumio volumio[1142]: info: Apr 30 06:35:45 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand stop took 62 milliseconds Apr 30 06:35:45 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/aa/01/c6/aa01c6ad-98b2-8b45-0749-50d6c61fb7a1/source/600x600bb.jpg","name":"Chicago - Stay the Night","title":"Stay the Night","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 30 06:35:45 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: No code Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: Apr 30 06:35:45 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:45 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand status took 149 milliseconds Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:45 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 30 06:35:45 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: No code Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: ------------------------------ 249ms Apr 30 06:35:45 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:45 volumio volumio[1142]: info: ------------------------------ 168ms Apr 30 06:35:45 volumio volumio[1142]: info: Apr 30 06:35:45 volumio volumio[1142]: ---------------------------- MPD announces state update: options Apr 30 06:35:45 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:45 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Apr 30 06:35:45 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:45 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 30 06:35:45 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: No code Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:45 volumio volumio[1142]: info: ------------------------------ 47ms Apr 30 06:35:45 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:45 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:45 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:45 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:45 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 1ms Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 1ms Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:46 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:46 volumio volumio[1142]: info: sendMpdCommand status took 73 milliseconds Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:46 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:46 volumio volumio[1142]: info: Apr 30 06:35:46 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:46 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:46 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:46 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:46 volumio volumio[1142]: verbose: In UPNP mode Apr 30 06:35:46 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Human Behaviour","artist":"Bjork","album":"The Very Best of MTV Unplugged, Volume 2","uri":"http://192.168.1.102:41399/ced52060bbf2bfc8ca24d06a604a7ded/primary%3ADownload%2FTopping%2FVarious%20-%20The%20Very%20Best%20Of%20MTV%20Unplugged.%20Vol.%201-2%20(2002-2003)%2F2003%20-%20Vol.%202%2F10%20(Bjork)%20Human%20Behaviour.flac","trackType":"flac"} Apr 30 06:35:46 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 83ms Apr 30 06:35:46 volumio volumio[1142]: info: sendMpdCommand status took 7 milliseconds Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:46 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 06:35:46 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:46 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:46 volumio volumio[1142]: verbose: In UPNP mode Apr 30 06:35:46 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"854 Kbps","isStreaming":false,"title":"Human Behaviour","artist":"Bjork","album":"The Very Best of MTV Unplugged, Volume 2","uri":"http://192.168.1.102:41399/ced52060bbf2bfc8ca24d06a604a7ded/primary%3ADownload%2FTopping%2FVarious%20-%20The%20Very%20Best%20Of%20MTV%20Unplugged.%20Vol.%201-2%20(2002-2003)%2F2003%20-%20Vol.%202%2F10%20(Bjork)%20Human%20Behaviour.flac","trackType":"flac"} Apr 30 06:35:46 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Apr 30 06:35:46 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:46 volumio volumio[1142]: info: ------------------------------ 55ms Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:46 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:46 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 30 06:35:46 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 30 06:35:46 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 30 06:35:47 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 30 06:35:47 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:47 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:47 volumio volumio[1142]: info: Consume mode Apr 30 06:35:47 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:47 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 4ms Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:47 volumio volumio[1142]: info: Apr 30 06:35:47 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:47 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:47 volumio volumio[1142]: info: Starting UPNP Playback Apr 30 06:35:47 volumio volumio[1142]: info: Preparing playback through UPNP Apr 30 06:35:47 volumio volumio[1142]: info: Consume mode Apr 30 06:35:47 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:47 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 06:35:47 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:48 volumio volumio[1142]: info: Apr 30 06:35:48 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:48 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:48 volumio volumio[1142]: info: Apr 30 06:35:48 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:48 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:48 volumio volumio[1142]: info: ------------------------------ 5ms Apr 30 06:35:48 volumio volumio[1142]: info: ------------------------------ 4ms Apr 30 06:35:48 volumio volumio[1142]: info: Apr 30 06:35:48 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:48 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:48 volumio volumio[1142]: info: ------------------------------ 10ms Apr 30 06:35:48 volumio volumio[1142]: info: Apr 30 06:35:48 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:48 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:48 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:48 volumio volumio[1142]: info: Apr 30 06:35:48 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:48 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:48 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:48 volumio volumio[1142]: info: Apr 30 06:35:48 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:48 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:48 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:49 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::serviceStop Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: ControllerMpd::stop Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Apr 30 06:35:49 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:49 volumio volumio[1142]: info: Adding Item to queue: webeighties/2 Apr 30 06:35:49 volumio volumio[1142]: info: Using cached record of: webeighties/2 Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::play index 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::play index undefined Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949414] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949415] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Apr 30 06:35:49 volumio volumio[1142]: info: Apr 30 06:35:49 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand stop took 64 milliseconds Apr 30 06:35:49 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand status took 44 milliseconds Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:49 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:49 volumio volumio[1142]: info: ------------------------------ 87ms Apr 30 06:35:49 volumio volumio[1142]: info: touch_display: Setting screensaver timeout to 10 seconds. Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949577] [80s80s] received new event containing 2 songs. Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand stop took 7 milliseconds Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:35:49 volumio volumio[1142]: info: Apr 30 06:35:49 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:49 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand clear took 6 milliseconds Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 30 06:35:49 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:49 volumio volumio[1142]: info: Apr 30 06:35:49 volumio volumio[1142]: ---------------------------- MPD announces state update: options Apr 30 06:35:49 volumio volumio[1142]: info: ------------------------------ 12ms Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand consume 1 took 7 milliseconds Apr 30 06:35:49 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949607] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Apr 30 06:35:49 volumio volumio[1142]: info: Apr 30 06:35:49 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:49 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand status took 12 milliseconds Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 12 milliseconds Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:49 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:35:49 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:49 volumio volumio[1142]: info: ------------------------------ 57ms Apr 30 06:35:49 volumio volumio[1142]: info: ------------------------------ 49ms Apr 30 06:35:49 volumio volumio[1142]: info: sendMpdCommand play took 33 milliseconds Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949667] [80s80s] Pushing the next song state: Chicago - Stay the Night Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:49 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:49 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/aa/01/c6/aa01c6ad-98b2-8b45-0749-50d6c61fb7a1/source/600x600bb.jpg","name":"Chicago - Stay the Night","title":"Stay the Night","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 30 06:35:49 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:49 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949675] [80s80s] PlayNextTrack API delay: 5 Apr 30 06:35:49 volumio volumio[1142]: info: [1714458949678] [80s80s] Setting timer to: 218000 milliseconds. Apr 30 06:35:50 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 17 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::serviceStop Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Apr 30 06:35:50 volumio volumio[1142]: info: ControllerMpd::stop Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Apr 30 06:35:50 volumio volumio[1142]: info: Preload queue cleared Apr 30 06:35:50 volumio volumio[1142]: info: Adding Item to queue: webeighties/3 Apr 30 06:35:50 volumio volumio[1142]: info: Exploding uri webeighties/3 in service 80s80s Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950311] [80s80s] explodeUri: webeighties/3 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::saveQueue Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::play index 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::stop Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::play index undefined Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950361] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950362] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Apr 30 06:35:50 volumio volumio[1142]: info: Apr 30 06:35:50 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand stop took 98 milliseconds Apr 30 06:35:50 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/aa/01/c6/aa01c6ad-98b2-8b45-0749-50d6c61fb7a1/source/600x600bb.jpg","name":"Chicago - Stay the Night","title":"Stay the Night","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 30 06:35:50 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:50 volumio volumio[1142]: info: No code Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand status took 95 milliseconds Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:50 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:50 volumio volumio[1142]: info: ------------------------------ 128ms Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950731] [80s80s] received new event containing 2 songs. Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand stop took 1 milliseconds Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 06:35:50 volumio volumio[1142]: info: Apr 30 06:35:50 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:50 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand clear took 2 milliseconds Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 30 06:35:50 volumio volumio[1142]: error: updateQueue error: null Apr 30 06:35:50 volumio volumio[1142]: info: ------------------------------ 2ms Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand consume 1 took 1 milliseconds Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950738] [80s80s] adding url: http://streams.80s80s.de/party/mp3-192/volumio Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" Apr 30 06:35:50 volumio volumio[1142]: info: Apr 30 06:35:50 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:50 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" took 2 milliseconds Apr 30 06:35:50 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Apr 30 06:35:50 volumio volumio[1142]: info: ------------------------------ 13ms Apr 30 06:35:50 volumio volumio[1142]: info: sendMpdCommand play took 7 milliseconds Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950758] [80s80s] Pushing the next song state: Men At Work - Who Can It Be Now? Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music124/v4/82/c5/cb/82c5cbc1-daca-1137-6e85-85d6eb8e830b/source/600x600bb.jpg","name":"Men At Work - Who Can It Be Now?","title":"Who Can It Be Now?","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"198","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 30 06:35:50 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Apr 30 06:35:50 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950769] [80s80s] PlayNextTrack API delay: 5 Apr 30 06:35:50 volumio volumio[1142]: info: [1714458950772] [80s80s] Setting timer to: 198000 milliseconds. Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:50 volumio volumio[1142]: info: CorePlayQueue::getTrack 1 Apr 30 06:35:52 volumio volumio[1142]: info: Apr 30 06:35:52 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:52 volumio volumio[1142]: info: Apr 30 06:35:52 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand status took 32 milliseconds Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:52 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:52 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:52 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:52 volumio volumio[1142]: info: ------------------------------ 49ms Apr 30 06:35:52 volumio volumio[1142]: info: Apr 30 06:35:52 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:52 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:52 volumio volumio[1142]: info: Apr 30 06:35:52 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:52 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:52 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:52 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:52 volumio volumio[1142]: info: ------------------------------ 23ms Apr 30 06:35:52 volumio volumio[1142]: info: Apr 30 06:35:52 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:52 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:52 volumio volumio[1142]: info: Apr 30 06:35:52 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:52 volumio volumio[1142]: info: ------------------------------ 9ms Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:52 volumio volumio[1142]: info: ------------------------------ 6ms Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand status took 5 milliseconds Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:52 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:52 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:52 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:52 volumio volumio[1142]: info: ------------------------------ 15ms Apr 30 06:35:52 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 30 06:35:52 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:52 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:52 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:52 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:52 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:52 volumio volumio[1142]: info: ------------------------------ 12ms Apr 30 06:35:54 volumio volumio[1142]: info: Apr 30 06:35:54 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Apr 30 06:35:54 volumio volumio[1142]: info: Ignoring MPD Status Update Apr 30 06:35:54 volumio volumio[1142]: info: Apr 30 06:35:54 volumio volumio[1142]: ---------------------------- MPD announces state update: player Apr 30 06:35:54 volumio volumio[1142]: info: ControllerMpd::getState Apr 30 06:35:54 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 30 06:35:54 volumio volumio[1142]: info: ------------------------------ 3ms Apr 30 06:35:54 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Apr 30 06:35:54 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 30 06:35:54 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 06:35:54 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 06:35:54 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Apr 30 06:35:54 volumio volumio[1142]: info: ControllerMpd::pushState Apr 30 06:35:54 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 30 06:35:54 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 30 06:35:54 volumio volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 30 06:35:54 volumio volumio[1142]: info: ------------------------------ 7ms Apr 30 06:36:24 volumio volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 06:36:24 volumio volumio[1142]: Error: connect ETIMEDOUT 146.75.122.79:443 Apr 30 06:36:24 volumio volumio[1142]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 30 06:36:24 volumio volumio[1142]: errno: -110, Apr 30 06:36:24 volumio volumio[1142]: code: 'ETIMEDOUT', Apr 30 06:36:24 volumio volumio[1142]: syscall: 'connect', Apr 30 06:36:24 volumio volumio[1142]: address: '146.75.122.79', Apr 30 06:36:24 volumio volumio[1142]: port: 443 Apr 30 06:36:24 volumio volumio[1142]: } Apr 30 06:36:24 volumio volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 06:36:26 volumio sudo[8911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-30 06:35 Apr 30 06:36:26 volumio sudo[8911]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"