-- Logs begin at Sun 2023-07-23 15:12:00 PST, end at Sun 2023-07-23 20:13:33 PST. -- Jul 23 20:12:00 volumio volumio[31708]: info: CoreCommandRouter::Reload Ui Jul 23 20:12:00 volumio sudo[686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 23 20:12:00 volumio sudo[686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 20:12:00 volumio sudo[686]: pam_unix(sudo:session): session closed for user root Jul 23 20:12:01 volumio sudo[689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 23 20:12:01 volumio sudo[689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 20:12:01 volumio sudo[689]: pam_unix(sudo:session): session closed for user root Jul 23 20:12:01 volumio volumio[31708]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::volumioGetState Jul 23 20:12:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 17 Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::volumioGetVisibleSources Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 23 20:12:01 volumio volumio[31708]: Disk list : [] Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 23 20:12:01 volumio volumio[31708]: info: Received Get System Version Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 23 20:12:01 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jul 23 20:12:03 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 23 20:12:03 volumio volumio[31708]: info: Received Get System Info Jul 23 20:12:03 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 20:12:03 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 20:12:03 volumio volumio[31708]: info: Discovery: Getting this device information Jul 23 20:12:03 volumio volumio[31708]: info: CoreCommandRouter::volumioGetState Jul 23 20:12:03 volumio volumio[31708]: info: CorePlayQueue::getTrack 17 Jul 23 20:12:03 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 20:12:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 23 20:12:04 volumio volumio[31708]: info: Received Get System Info Jul 23 20:12:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 20:12:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 20:12:04 volumio volumio[31708]: info: Discovery: Getting this device information Jul 23 20:12:04 volumio volumio[31708]: info: CoreCommandRouter::volumioGetState Jul 23 20:12:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 17 Jul 23 20:12:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 20:12:05 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 23 20:12:06 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 23 20:12:12 volumio ntpd[777]: Soliciting pool server 222.127.1.27 Jul 23 20:12:16 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 23 20:12:16 volumio volumio[31708]: info: In handleBrowseUri, curUri=spotify Jul 23 20:12:16 volumio volumio[31708]: info: CoreCommandRouter::volumioGetQueue Jul 23 20:12:16 volumio volumio[31708]: info: CoreStateMachine::getQueue Jul 23 20:12:16 volumio volumio[31708]: info: CorePlayQueue::getQueue Jul 23 20:12:16 volumio volumio[31708]: info: Listing playlists Jul 23 20:12:16 volumio volumio[31708]: error: An error occurred while listing Spotify featured playlists WebapiError: certificate is not yet valid Jul 23 20:12:16 volumio volumio[31708]: info: An error occurred while getting Spotify ROOT Discover Folders: Jul 23 20:12:16 volumio volumio[31708]: error: An error occurred while listing Spotify new albums WebapiError: certificate is not yet valid Jul 23 20:12:16 volumio volumio[31708]: error: An error occurred while listing Spotify categories WebapiError: certificate is not yet valid Jul 23 20:12:16 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:16 volumio ntpd[777]: Soliciting pool server 222.127.1.26 Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 23 20:12:20 volumio volumio[31708]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 23 20:12:20 volumio volumio[31708]: info: Received Get System Version Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 23 20:12:20 volumio volumio[31708]: info: Received Get System Info Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 20:12:20 volumio volumio[31708]: info: Discovery: Getting this device information Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::volumioGetState Jul 23 20:12:20 volumio volumio[31708]: info: CorePlayQueue::getTrack 17 Jul 23 20:12:20 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 20:12:26 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 23 20:12:26 volumio volumio[31708]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks Jul 23 20:12:26 volumio volumio[31708]: error: An error occurred while listing Spotify recent tracks WebapiError: certificate is not yet valid Jul 23 20:12:26 volumio volumio[31708]: error: Failed to execute browseSource: Jul 23 20:12:29 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 23 20:12:29 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:33 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Jul 23 20:12:33 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:33 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri Jul 23 20:12:33 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:36 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jul 23 20:12:36 volumio volumio[31708]: info: Calm Radio root browse Jul 23 20:12:36 volumio volumio[31708]: info: Getting Calm Radio categories data Jul 23 20:12:37 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jul 23 20:12:37 volumio volumio[31708]: info: Calm Radio root browse Jul 23 20:12:37 volumio volumio[31708]: info: Getting Calm Radio categories data Jul 23 20:12:37 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jul 23 20:12:37 volumio volumio[31708]: info: Calm Radio root browse Jul 23 20:12:37 volumio volumio[31708]: info: Getting Calm Radio categories data Jul 23 20:12:38 volumio ntpd[777]: Soliciting pool server 222.127.1.23 Jul 23 20:12:46 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jul 23 20:12:46 volumio volumio[31708]: info: [1690114366911] [80s80s] handleBrowseUri curUri: 80s80s Jul 23 20:12:46 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jul 23 20:12:49 volumio volumio[31708]: info: [1690114369038] [80s80s] handleBrowseUri curUri: 80s80s/eighties Jul 23 20:12:49 volumio volumio[31708]: info: [1690114369038] [80s80s] getRadioContent url: eighties Jul 23 20:12:49 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:49 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::stPlaybackTimer Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 17 Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::serviceStop Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 17 Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::serviceStop Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::stop Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:12:49 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:49 volumio volumio[31708]: info: Adding Item to queue: webeighties/0 Jul 23 20:12:49 volumio volumio[31708]: info: Exploding uri webeighties/0 in service 80s80s Jul 23 20:12:49 volumio volumio[31708]: info: [1690114369269] [80s80s] explodeUri: webeighties/0 Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: [1690114369282] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Jul 23 20:12:49 volumio volumio[31708]: info: [1690114369283] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Jul 23 20:12:49 volumio volumio[31708]: info: Jul 23 20:12:49 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand stop took 33 milliseconds Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand status took 1 milliseconds Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:49 volumio volumio[31708]: info: Jul 23 20:12:49 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:49 volumio volumio[31708]: info: Jul 23 20:12:49 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:49 volumio volumio[31708]: info: ------------------------------ 17ms Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 23 20:12:49 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 23 20:12:49 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:49 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:49 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:49 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:49 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:49 volumio volumio[31708]: info: ------------------------------ 24ms Jul 23 20:12:49 volumio volumio[31708]: info: ------------------------------ 23ms Jul 23 20:12:50 volumio volumio[31708]: info: [1690114370012] [80s80s] received new event containing 2 songs. Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand stop took 2 milliseconds Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:50 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand clear took 2 milliseconds Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:50 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:50 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:50 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces state update: options Jul 23 20:12:50 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand consume 1 took 6 milliseconds Jul 23 20:12:50 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:12:50 volumio volumio[31708]: info: [1690114370027] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces state update: options Jul 23 20:12:50 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces state update: options Jul 23 20:12:50 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:50 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 7 milliseconds Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:12:50 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:50 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:50 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:50 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:50 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:50 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:50 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 29ms Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 30ms Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 28ms Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:50 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:50 volumio volumio[31708]: info: Jul 23 20:12:50 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:50 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 31ms Jul 23 20:12:50 volumio volumio[31708]: info: sendMpdCommand play took 27 milliseconds Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:12:50 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:12:50 volumio volumio[31708]: info: [1690114370065] [80s80s] Pushing the next song state: Michael Jackson - Dirty Diana Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/9f/ac/1c/9fac1c50-8472-8c43-3448-59205cd580a7/source/600x600bb.jpg","name":"Michael Jackson - Dirty Diana","title":"Dirty Diana","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"259","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:12:50 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:12:50 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:12:50 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:12:50 volumio volumio[31708]: info: [1690114370069] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:12:50 volumio volumio[31708]: info: [1690114370096] [80s80s] Setting timer to: 34306829931 milliseconds. Jul 23 20:12:50 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306829906 does not fit into a 32-bit signed integer. Jul 23 20:12:50 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:50 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 22ms Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 20ms Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 19ms Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 20ms Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 15ms Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 13ms Jul 23 20:12:52 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::stPlaybackTimer Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::serviceStop Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::serviceStop Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::stop Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:12:52 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:52 volumio volumio[31708]: info: Adding Item to queue: webeighties/11 Jul 23 20:12:52 volumio volumio[31708]: info: Exploding uri webeighties/11 in service 80s80s Jul 23 20:12:52 volumio volumio[31708]: info: [1690114372788] [80s80s] explodeUri: webeighties/11 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: [1690114372799] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/hamburg/mp3-192/volumio Jul 23 20:12:52 volumio volumio[31708]: info: [1690114372799] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=61&count=2 Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand stop took 18 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/9f/ac/1c/9fac1c50-8472-8c43-3448-59205cd580a7/source/600x600bb.jpg","name":"Michael Jackson - Dirty Diana","title":"Dirty Diana","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"259","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:12:52 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::syncState stateService stop Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: No code Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: Jul 23 20:12:52 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 23 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 10 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:12:52 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:52 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:52 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:52 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 40ms Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 27ms Jul 23 20:12:52 volumio volumio[31708]: info: ------------------------------ 26ms Jul 23 20:12:53 volumio volumio[31708]: info: [1690114373565] [80s80s] received new event containing 2 songs. Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand stop took 1 milliseconds Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:53 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand clear took 2 milliseconds Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:53 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:53 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:53 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:12:53 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:12:53 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand consume 1 took 5 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:12:53 volumio volumio[31708]: info: [1690114373576] [80s80s] adding url: http://streams.80s80s.de/hamburg/mp3-192/volumio Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/hamburg/mp3-192/volumio" Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:53 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/hamburg/mp3-192/volumio" took 2 milliseconds Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:53 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:12:53 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 8ms Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand play took 5 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:12:53 volumio volumio[31708]: info: [1690114373587] [80s80s] Pushing the next song state: Michael Jackson - Dirty Diana Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/9f/ac/1c/9fac1c50-8472-8c43-3448-59205cd580a7/source/600x600bb.jpg","name":"Michael Jackson - Dirty Diana","title":"Dirty Diana","artist":"80s80s DAB Hamburg","album":"","streaming":true,"disableUiControls":true,"duration":"258","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:12:53 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:12:53 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:12:53 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:12:53 volumio volumio[31708]: info: [1690114373588] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:12:53 volumio volumio[31708]: info: [1690114373594] [80s80s] Setting timer to: 34306844412 milliseconds. Jul 23 20:12:53 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306844387 does not fit into a 32-bit signed integer. Jul 23 20:12:53 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:53 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:53 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:53 volumio volumio[31708]: info: Jul 23 20:12:53 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:12:53 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand status took 10 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand clearerror took 6 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:12:53 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:53 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 20ms Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand clearerror took 11 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 23 20:12:53 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:53 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:12:53 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:53 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:53 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:53 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:53 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:53 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 33ms Jul 23 20:12:53 volumio volumio[31708]: info: ------------------------------ 27ms Jul 23 20:12:59 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::stPlaybackTimer Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::serviceStop Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::serviceStop Jul 23 20:12:59 volumio volumio[31708]: info: ControllerMpd::stop Jul 23 20:12:59 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:12:59 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:12:59 volumio volumio[31708]: info: Adding Item to queue: webeighties/7 Jul 23 20:12:59 volumio volumio[31708]: info: Exploding uri webeighties/7 in service 80s80s Jul 23 20:12:59 volumio volumio[31708]: info: [1690114379414] [80s80s] explodeUri: webeighties/7 Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: [1690114379424] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/mj/mp3-192/volumio Jul 23 20:12:59 volumio volumio[31708]: info: [1690114379424] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=156&count=2 Jul 23 20:12:59 volumio volumio[31708]: info: sendMpdCommand stop took 16 milliseconds Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/9f/ac/1c/9fac1c50-8472-8c43-3448-59205cd580a7/source/600x600bb.jpg","name":"Michael Jackson - Dirty Diana","title":"Dirty Diana","artist":"80s80s DAB Hamburg","album":"","streaming":true,"disableUiControls":true,"duration":"258","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:12:59 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::syncState stateService stop Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:12:59 volumio volumio[31708]: info: No code Jul 23 20:12:59 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:12:59 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:12:59 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:00 volumio volumio[31708]: info: [1690114380122] [80s80s] received new event containing 2 songs. Jul 23 20:13:00 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:00 volumio volumio[31708]: info: sendMpdCommand stop took 1 milliseconds Jul 23 20:13:00 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:13:00 volumio volumio[31708]: info: Jul 23 20:13:00 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:00 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:00 volumio volumio[31708]: info: sendMpdCommand clear took 1 milliseconds Jul 23 20:13:00 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:13:00 volumio volumio[31708]: info: Jul 23 20:13:00 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:00 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:00 volumio volumio[31708]: info: Jul 23 20:13:00 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:00 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:00 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:00 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:00 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:00 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:00 volumio volumio[31708]: info: sendMpdCommand consume 1 took 4 milliseconds Jul 23 20:13:00 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:00 volumio volumio[31708]: info: ------------------------------ 2ms Jul 23 20:13:00 volumio volumio[31708]: info: [1690114380131] [80s80s] adding url: http://streams.80s80s.de/mj/mp3-192/volumio Jul 23 20:13:00 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" Jul 23 20:13:00 volumio volumio[31708]: info: Jul 23 20:13:00 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:00 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:00 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" took 2 milliseconds Jul 23 20:13:00 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:13:00 volumio volumio[31708]: info: Jul 23 20:13:00 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:00 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:00 volumio volumio[31708]: info: Jul 23 20:13:00 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:00 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:00 volumio volumio[31708]: info: ------------------------------ 8ms Jul 23 20:13:00 volumio volumio[31708]: info: sendMpdCommand play took 5 milliseconds Jul 23 20:13:00 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:00 volumio volumio[31708]: info: [1690114380141] [80s80s] Pushing the next song state: Michael Jackson - Give In to Me Jul 23 20:13:00 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:00 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:00 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:00 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/9d/42/c9/9d42c9e0-16c1-4e89-8c84-388ce9e85e3b/source/600x600bb.jpg","name":"Michael Jackson - Give In to Me","title":"Give In to Me","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"273","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:00 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:00 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:13:00 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:00 volumio volumio[31708]: info: [1690114380142] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:13:00 volumio volumio[31708]: info: [1690114380144] [80s80s] Setting timer to: 34306906858 milliseconds. Jul 23 20:13:00 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306906833 does not fit into a 32-bit signed integer. Jul 23 20:13:00 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:13:00 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:00 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:13:01 volumio ntpd[777]: Soliciting pool server 222.127.1.21 Jul 23 20:13:01 volumio volumio[31708]: info: Jul 23 20:13:01 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:01 volumio volumio[31708]: info: Jul 23 20:13:01 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:01 volumio volumio[31708]: info: Jul 23 20:13:01 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:01 volumio volumio[31708]: info: Jul 23 20:13:01 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:01 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:01 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:01 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:01 volumio volumio[31708]: info: Jul 23 20:13:01 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:01 volumio volumio[31708]: info: Jul 23 20:13:01 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:01 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:01 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:01 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:01 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:01 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:01 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:01 volumio volumio[31708]: info: ------------------------------ 18ms Jul 23 20:13:01 volumio volumio[31708]: info: ------------------------------ 17ms Jul 23 20:13:01 volumio volumio[31708]: info: ------------------------------ 15ms Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 20:13:01 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:01 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:01 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:01 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:01 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:01 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:01 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:01 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:01 volumio volumio[31708]: info: ------------------------------ 11ms Jul 23 20:13:01 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:02 volumio volumio[31708]: info: Jul 23 20:13:02 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:02 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:02 volumio volumio[31708]: info: Jul 23 20:13:02 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:02 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:02 volumio volumio[31708]: info: Jul 23 20:13:02 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:02 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:02 volumio volumio[31708]: info: Jul 23 20:13:02 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:02 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:02 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:02 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:13:02 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:02 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:02 volumio volumio[31708]: info: Jul 23 20:13:02 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:02 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:02 volumio volumio[31708]: info: Jul 23 20:13:02 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:02 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:02 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 20:13:02 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:02 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:02 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:02 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:02 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:02 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:02 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:02 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:02 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:02 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:02 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:02 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:02 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:02 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:02 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:02 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:02 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:02 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:02 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:02 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:04 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::stPlaybackTimer Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::serviceStop Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::serviceStop Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::stop Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:13:04 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:04 volumio volumio[31708]: info: Adding Item to queue: webeighties/5 Jul 23 20:13:04 volumio volumio[31708]: info: Exploding uri webeighties/5 in service 80s80s Jul 23 20:13:04 volumio volumio[31708]: info: [1690114384877] [80s80s] explodeUri: webeighties/5 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: [1690114384887] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/italohits/mp3-192/volumio Jul 23 20:13:04 volumio volumio[31708]: info: [1690114384887] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=283&count=2 Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand stop took 34 milliseconds Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/9d/42/c9/9d42c9e0-16c1-4e89-8c84-388ce9e85e3b/source/600x600bb.jpg","name":"Michael Jackson - Give In to Me","title":"Give In to Me","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"273","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:04 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::syncState stateService stop Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: No code Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: Jul 23 20:13:04 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:04 volumio volumio[31708]: info: Jul 23 20:13:04 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:04 volumio volumio[31708]: info: Jul 23 20:13:04 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand status took 10 milliseconds Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:04 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:04 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:04 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:04 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:04 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:04 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:04 volumio volumio[31708]: info: ------------------------------ 28ms Jul 23 20:13:04 volumio volumio[31708]: info: ------------------------------ 27ms Jul 23 20:13:04 volumio volumio[31708]: info: ------------------------------ 27ms Jul 23 20:13:05 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:13:05 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:05 volumio volumio[31708]: info: Adding Item to queue: webeighties/5 Jul 23 20:13:05 volumio volumio[31708]: info: Using cached record of: webeighties/5 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385103] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/italohits/mp3-192/volumio Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385103] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=283&count=2 Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385462] [80s80s] received new event containing 2 songs. Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand stop took 1 milliseconds Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand clear took 2 milliseconds Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:05 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:05 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand consume 1 took 4 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385471] [80s80s] adding url: http://streams.80s80s.de/italohits/mp3-192/volumio Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" took 1 milliseconds Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand play took 4 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385478] [80s80s] Pushing the next song state: Max Him - Japanese Girl Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music128/v4/1e/17/ae/1e17aefa-7893-941f-29dc-5c952ad99c8a/859724833070_cover.jpg/600x600bb.jpg","name":"Max Him - Japanese Girl","title":"Japanese Girl","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"271","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:05 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385480] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385482] [80s80s] Setting timer to: 34306873520 milliseconds. Jul 23 20:13:05 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306873495 does not fit into a 32-bit signed integer. Jul 23 20:13:05 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385570] [80s80s] received new event containing 2 songs. Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand stop took 2 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:05 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:05 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand clear took 4 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:05 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:05 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:05 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:05 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 16ms Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand consume 1 took 14 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385596] [80s80s] adding url: http://streams.80s80s.de/italohits/mp3-192/volumio Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" Jul 23 20:13:05 volumio volumio[31708]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 24ms Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" took 4 milliseconds Jul 23 20:13:05 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: Jul 23 20:13:05 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:05 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:05 volumio volumio[31708]: info: sendMpdCommand play took 5 milliseconds Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:05 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385607] [80s80s] Pushing the next song state: Max Him - Japanese Girl Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music128/v4/1e/17/ae/1e17aefa-7893-941f-29dc-5c952ad99c8a/859724833070_cover.jpg/600x600bb.jpg","name":"Max Him - Japanese Girl","title":"Japanese Girl","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"271","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:05 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus play Jul 23 20:13:05 volumio volumio[31708]: info: Received an update from plugin. extracting info from payload Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:05 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385616] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:13:05 volumio volumio[31708]: info: [1690114385618] [80s80s] Setting timer to: 34306873384 milliseconds. Jul 23 20:13:05 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306873359 does not fit into a 32-bit signed integer. Jul 23 20:13:05 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:05 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 17ms Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:07 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:07 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:07 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 8ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 15ms Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 13ms Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:07 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:07 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:07 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:07 volumio volumio[31708]: info: Jul 23 20:13:07 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 11ms Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:07 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:07 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:07 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:07 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:10 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::stPlaybackTimer Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::serviceStop Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::serviceStop Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::stop Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:13:10 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:10 volumio volumio[31708]: info: Adding Item to queue: webeighties/3 Jul 23 20:13:10 volumio volumio[31708]: info: Exploding uri webeighties/3 in service 80s80s Jul 23 20:13:10 volumio volumio[31708]: info: [1690114390478] [80s80s] explodeUri: webeighties/3 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: [1690114390488] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Jul 23 20:13:10 volumio volumio[31708]: info: [1690114390488] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand stop took 23 milliseconds Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music128/v4/1e/17/ae/1e17aefa-7893-941f-29dc-5c952ad99c8a/859724833070_cover.jpg/600x600bb.jpg","name":"Max Him - Japanese Girl","title":"Japanese Girl","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"271","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:10 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::syncState stateService stop Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: No code Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: Jul 23 20:13:10 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:10 volumio volumio[31708]: info: Jul 23 20:13:10 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:10 volumio volumio[31708]: info: Jul 23 20:13:10 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:10 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:10 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:10 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:10 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:10 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:10 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:10 volumio volumio[31708]: info: ------------------------------ 23ms Jul 23 20:13:10 volumio volumio[31708]: info: ------------------------------ 22ms Jul 23 20:13:10 volumio volumio[31708]: info: ------------------------------ 21ms Jul 23 20:13:11 volumio volumio[31708]: info: [1690114391030] [80s80s] received new event containing 2 songs. Jul 23 20:13:11 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:11 volumio volumio[31708]: info: sendMpdCommand stop took 1 milliseconds Jul 23 20:13:11 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:13:11 volumio volumio[31708]: info: Jul 23 20:13:11 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:11 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:11 volumio volumio[31708]: info: sendMpdCommand clear took 2 milliseconds Jul 23 20:13:11 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:13:11 volumio volumio[31708]: info: Jul 23 20:13:11 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:11 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:11 volumio volumio[31708]: info: Jul 23 20:13:11 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:11 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:11 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:11 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:11 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:11 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:11 volumio volumio[31708]: info: sendMpdCommand consume 1 took 4 milliseconds Jul 23 20:13:11 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:11 volumio volumio[31708]: info: ------------------------------ 2ms Jul 23 20:13:11 volumio volumio[31708]: info: [1690114391038] [80s80s] adding url: http://streams.80s80s.de/party/mp3-192/volumio Jul 23 20:13:11 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" Jul 23 20:13:11 volumio volumio[31708]: info: Jul 23 20:13:11 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:11 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:11 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" took 1 milliseconds Jul 23 20:13:11 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:13:11 volumio volumio[31708]: info: Jul 23 20:13:11 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:11 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:11 volumio volumio[31708]: info: Jul 23 20:13:11 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:11 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:11 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:11 volumio volumio[31708]: info: sendMpdCommand play took 4 milliseconds Jul 23 20:13:11 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:11 volumio volumio[31708]: info: ------------------------------ 2ms Jul 23 20:13:11 volumio volumio[31708]: info: [1690114391047] [80s80s] Pushing the next song state: The Smiths - This Charming Man Jul 23 20:13:11 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:11 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:11 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:11 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music115/v4/a1/13/2c/a1132cc7-52f4-cc13-da68-7e5357da8c30/source/600x600bb.jpg","name":"The Smiths - This Charming Man","title":"This Charming Man","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"160","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:11 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:11 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:13:11 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:11 volumio volumio[31708]: info: [1690114391048] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:13:11 volumio volumio[31708]: info: [1690114391050] [80s80s] Setting timer to: 34306754952 milliseconds. Jul 23 20:13:11 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306754927 does not fit into a 32-bit signed integer. Jul 23 20:13:11 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:13:11 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:11 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:13:12 volumio volumio[31708]: info: Jul 23 20:13:12 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:12 volumio volumio[31708]: info: Jul 23 20:13:12 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:12 volumio volumio[31708]: info: Jul 23 20:13:12 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:12 volumio volumio[31708]: info: Jul 23 20:13:12 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand status took 7 milliseconds Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand status took 8 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:12 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:12 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:12 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:12 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:12 volumio volumio[31708]: info: Jul 23 20:13:12 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:12 volumio volumio[31708]: info: Jul 23 20:13:12 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:12 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:12 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:12 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:12 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:12 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:12 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:12 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:12 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:12 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:12 volumio volumio[31708]: info: ------------------------------ 19ms Jul 23 20:13:12 volumio volumio[31708]: info: ------------------------------ 18ms Jul 23 20:13:12 volumio volumio[31708]: info: ------------------------------ 17ms Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:12 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:12 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:12 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:12 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:12 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:12 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:12 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:12 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:12 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:12 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:12 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:13 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:13 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:13 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:13 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:13 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:13 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:13 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:13 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:13 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:13 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:13 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:13 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 13ms Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 11ms Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:13 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:13 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 2ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:13 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:13 volumio volumio[31708]: info: Jul 23 20:13:13 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:13 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:13 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:13 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:13 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:13 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:13 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:13 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:13 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:13 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:13 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:13 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:13 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:13 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:17 volumio ntpd[777]: Soliciting pool server 222.127.1.18 Jul 23 20:13:21 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::ClearQueue Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::stPlaybackTimer Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::serviceStop Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::serviceStop Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::stop Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::clearPlayQueue Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::addQueueItems Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::addQueueItems Jul 23 20:13:21 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:21 volumio volumio[31708]: info: Adding Item to queue: webeighties/2 Jul 23 20:13:21 volumio volumio[31708]: info: Exploding uri webeighties/2 in service 80s80s Jul 23 20:13:21 volumio volumio[31708]: info: [1690114401495] [80s80s] explodeUri: webeighties/2 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushQueue Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::saveQueue Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::updateTrackBlock Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrackBlock Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPlay Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::play index 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::stop Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::play index undefined Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::startPlaybackTimer Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: [1690114401507] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Jul 23 20:13:21 volumio volumio[31708]: info: [1690114401507] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand stop took 25 milliseconds Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music115/v4/a1/13/2c/a1132cc7-52f4-cc13-da68-7e5357da8c30/source/600x600bb.jpg","name":"The Smiths - This Charming Man","title":"This Charming Man","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"160","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:21 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::syncState stateService stop Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: No code Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: Jul 23 20:13:21 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:21 volumio volumio[31708]: info: Jul 23 20:13:21 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:21 volumio volumio[31708]: info: Jul 23 20:13:21 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand status took 7 milliseconds Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:21 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:21 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:21 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:21 volumio volumio[31708]: info: CoreStateMachine::pushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: CoreCommandRouter::volumioPushState Jul 23 20:13:21 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:21 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:21 volumio volumio[31708]: info: ------------------------------ 24ms Jul 23 20:13:21 volumio volumio[31708]: info: ------------------------------ 24ms Jul 23 20:13:21 volumio volumio[31708]: info: ------------------------------ 23ms Jul 23 20:13:22 volumio volumio[31708]: info: [1690114402140] [80s80s] received new event containing 2 songs. Jul 23 20:13:22 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 20:13:22 volumio volumio[31708]: info: sendMpdCommand stop took 1 milliseconds Jul 23 20:13:22 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 20:13:22 volumio volumio[31708]: info: Jul 23 20:13:22 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:22 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:22 volumio volumio[31708]: info: sendMpdCommand clear took 2 milliseconds Jul 23 20:13:22 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 23 20:13:22 volumio volumio[31708]: info: Jul 23 20:13:22 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:22 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:22 volumio volumio[31708]: info: Jul 23 20:13:22 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:22 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:22 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:22 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:22 volumio volumio[31708]: error: updateQueue error: null Jul 23 20:13:22 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:22 volumio volumio[31708]: info: sendMpdCommand consume 1 took 7 milliseconds Jul 23 20:13:22 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:22 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:22 volumio volumio[31708]: info: [1690114402156] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Jul 23 20:13:22 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Jul 23 20:13:22 volumio volumio[31708]: info: Jul 23 20:13:22 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:22 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:22 volumio volumio[31708]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 3 milliseconds Jul 23 20:13:22 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand play Jul 23 20:13:22 volumio volumio[31708]: info: Jul 23 20:13:22 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:22 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:22 volumio volumio[31708]: info: Jul 23 20:13:22 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:22 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:22 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:22 volumio volumio[31708]: info: sendMpdCommand play took 6 milliseconds Jul 23 20:13:22 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:22 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:22 volumio volumio[31708]: info: [1690114402171] [80s80s] Pushing the next song state: Mr. Mister - Broken Wings Jul 23 20:13:22 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:22 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:22 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:22 volumio volumio[31708]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music125/v4/29/f8/28/29f82819-8748-7731-62e8-ec96648fa56f/source/600x600bb.jpg","name":"Mr. Mister - Broken Wings","title":"Broken Wings","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"264","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 23 20:13:22 volumio volumio[31708]: verbose: CURRENT POSITION 0 Jul 23 20:13:22 volumio volumio[31708]: info: CoreStateMachine::syncState stateService play Jul 23 20:13:22 volumio volumio[31708]: info: CoreStateMachine::syncState currentStatus stop Jul 23 20:13:22 volumio volumio[31708]: info: [1690114402175] [80s80s] PlayNextTrack API delay: 30 Jul 23 20:13:22 volumio volumio[31708]: info: [1690114402176] [80s80s] Setting timer to: 34306661825 milliseconds. Jul 23 20:13:22 volumio volumio[31708]: (node:31708) TimeoutOverflowWarning: 34306661800 does not fit into a 32-bit signed integer. Jul 23 20:13:22 volumio volumio[31708]: Timeout duration was set to 1. Jul 23 20:13:22 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:22 volumio volumio[31708]: info: CorePlayQueue::getTrack 1 Jul 23 20:13:22 volumio ntpd[777]: Soliciting pool server 222.127.1.19 Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 13ms Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 14ms Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 8ms Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:24 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:24 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:24 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:24 volumio volumio[31708]: info: Jul 23 20:13:24 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 6ms Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 5ms Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 4 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:24 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:24 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:24 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:24 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:24 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 13ms Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 11ms Jul 23 20:13:24 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:26 volumio volumio[31708]: info: Jul 23 20:13:26 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:26 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:26 volumio volumio[31708]: info: Jul 23 20:13:26 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:26 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:26 volumio volumio[31708]: info: Jul 23 20:13:26 volumio volumio[31708]: ---------------------------- MPD announces system playlist update Jul 23 20:13:26 volumio volumio[31708]: info: Ignoring MPD Status Update Jul 23 20:13:26 volumio volumio[31708]: info: Jul 23 20:13:26 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:26 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:26 volumio volumio[31708]: info: ------------------------------ 7ms Jul 23 20:13:26 volumio volumio[31708]: info: ------------------------------ 4ms Jul 23 20:13:26 volumio volumio[31708]: info: ------------------------------ 3ms Jul 23 20:13:26 volumio volumio[31708]: info: Jul 23 20:13:26 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:26 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:26 volumio volumio[31708]: info: Jul 23 20:13:26 volumio volumio[31708]: ---------------------------- MPD announces state update: player Jul 23 20:13:26 volumio volumio[31708]: info: ControllerMpd::getState Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:13:26 volumio volumio[31708]: info: sendMpdCommand status took 5 milliseconds Jul 23 20:13:26 volumio volumio[31708]: info: sendMpdCommand status took 3 milliseconds Jul 23 20:13:26 volumio volumio[31708]: info: sendMpdCommand status took 2 milliseconds Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::parseState Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 20:13:26 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:26 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 20:13:26 volumio volumio[31708]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:26 volumio volumio[31708]: verbose: ControllerMpd::parseTrackInfo Jul 23 20:13:26 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:26 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:26 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:26 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:26 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:26 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:26 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:26 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:26 volumio volumio[31708]: info: ControllerMpd::pushState Jul 23 20:13:26 volumio volumio[31708]: info: CoreCommandRouter::servicePushState Jul 23 20:13:26 volumio volumio[31708]: info: CorePlayQueue::getTrack 0 Jul 23 20:13:26 volumio volumio[31708]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 23 20:13:26 volumio volumio[31708]: info: ------------------------------ 12ms Jul 23 20:13:26 volumio volumio[31708]: info: ------------------------------ 10ms Jul 23 20:13:26 volumio volumio[31708]: info: ------------------------------ 9ms Jul 23 20:13:32 volumio volumio[31708]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 23 20:13:32 volumio volumio[31708]: info: CURURI: albums:// Jul 23 20:13:32 volumio volumio[31708]: info: listAlbums - loading Albums from cache Jul 23 20:13:32 volumio volumio[31708]: info: Preload queue cleared Jul 23 20:13:33 volumio volumio[31708]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 20:13:33 volumio volumio[31708]: Error: certificate is not yet valid Jul 23 20:13:33 volumio volumio[31708]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jul 23 20:13:33 volumio volumio[31708]: at TLSSocket.emit (events.js:315:20) Jul 23 20:13:33 volumio volumio[31708]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jul 23 20:13:33 volumio volumio[31708]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jul 23 20:13:33 volumio volumio[31708]: code: 'CERT_NOT_YET_VALID' Jul 23 20:13:33 volumio volumio[31708]: } Jul 23 20:13:33 volumio volumio[31708]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 20:13:33 volumio sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-07-23 20:12 Jul 23 20:13:33 volumio sudo[809]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"