Sep 14 13:48:01 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Sep 14 13:48:01 home---speakers volumio[1142]: info: [ytmusic-browse] browseUri: ytmusic Sep 14 13:48:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:05 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 30 Sep 14 13:48:13 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 14 13:48:15 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:15 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 30 Sep 14 13:48:15 home---speakers volumio[1142]: info: Listing playlists Sep 14 13:48:15 home---speakers volumio[1142]: info: Listing playlists Sep 14 13:48:17 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 13:48:17 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 13:48:19 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 13:48:21 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 13:48:21 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 13:48:23 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 13:48:23 home---speakers volumio[1142]: info: Getting webradio selection Sep 14 13:48:25 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:25 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 30 Sep 14 13:48:35 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:35 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 30 Sep 14 13:48:35 home---speakers volumio[1142]: info: Listing playlists Sep 14 13:48:35 home---speakers volumio[1142]: info: Listing playlists Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::ClearQueue Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::stop Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::stPlaybackTimer Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::updateTrackBlock Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrackBlock Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 30 Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::serviceStop Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 30 Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::serviceStop Sep 14 13:48:36 home---speakers volumio[1142]: info: ControllerMpd::stop Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::clearPlayQueue Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::saveQueue Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushQueue Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::addQueueItems Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::addQueueItems Sep 14 13:48:36 home---speakers volumio[1142]: info: Preload queue cleared Sep 14 13:48:36 home---speakers volumio[1142]: info: Adding Item to queue: https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushQueue Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::saveQueue Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::updateTrackBlock Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrackBlock Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPlay Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::play index 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::stop Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::play index undefined Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::startPlaybackTimer Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: [1757872116069] ControllerWebradio::clearAddPlayTrack Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 13:48:36 home---speakers volumio[1142]: info: Sep 14 13:48:36 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand stop took 10 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand stop took 4 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: Sep 14 13:48:36 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:36 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:36 home---speakers volumio[1142]: info: Sep 14 13:48:36 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:36 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:36 home---speakers volumio[1142]: info: Sep 14 13:48:36 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:36 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand clear took 4 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:36 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:36 home---speakers volumio[1142]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 14 13:48:36 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:36 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:36 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:36 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:36 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:48:36 home---speakers volumio[1142]: info: ------------------------------ 7ms Sep 14 13:48:36 home---speakers volumio[1142]: info: ------------------------------ 7ms Sep 14 13:48:36 home---speakers volumio[1142]: info: Sep 14 13:48:36 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:36 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:36 home---speakers volumio[1142]: info: Sep 14 13:48:36 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:36 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:36 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:48:36 home---speakers volumio[1142]: info: ------------------------------ 9ms Sep 14 13:48:37 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" Sep 14 13:48:37 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:48:37 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:48:37 home---speakers volumio[1142]: info: Sep 14 13:48:37 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:37 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:37 home---speakers volumio[1142]: info: ------------------------------ 1827ms Sep 14 13:48:37 home---speakers volumio[1142]: info: ------------------------------ 1826ms Sep 14 13:48:37 home---speakers volumio[1142]: info: sendMpdCommand add "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" took 2 milliseconds Sep 14 13:48:37 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 13:48:37 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Sep 14 13:48:37 home---speakers volumio[1142]: info: Sep 14 13:48:37 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:37 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:37 home---speakers volumio[1142]: info: Sep 14 13:48:37 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:37 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:37 home---speakers volumio[1142]: info: ------------------------------ 3ms Sep 14 13:48:37 home---speakers volumio[1142]: info: sendMpdCommand play took 2 milliseconds Sep 14 13:48:37 home---speakers volumio[1142]: info: ------------------------------ 2ms Sep 14 13:48:37 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 16 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 16 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 16 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 19ms Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 3 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 2 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 2 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 29ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 29ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 14ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 14ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 16ms Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:39 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:39 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:39 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:39 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 2ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:39 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:39 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:39 home---speakers volumio[1142]: info: Sep 14 13:48:39 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 8 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 7 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 7ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 7 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 7 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Adele - Rolling In The Deep","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 12ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 7ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand status took 6 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Adele - Rolling In The Deep","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Adele - Rolling In The Deep","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Adele - Rolling In The Deep","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Adele - Rolling In The Deep","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 21ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 21ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 20ms Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 20ms Sep 14 13:48:39 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 14 13:48:39 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:39 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Adele - Rolling In The Deep","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Sep 14 13:48:39 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:39 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:39 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:39 home---speakers volumio[1142]: info: ------------------------------ 21ms Sep 14 13:48:45 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::ClearQueue Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::stop Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::stPlaybackTimer Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::updateTrackBlock Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrackBlock Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::serviceStop Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::serviceStop Sep 14 13:48:46 home---speakers volumio[1142]: info: [1757872126765] ControllerWebradio::stop Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::clearPlayQueue Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::saveQueue Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushQueue Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::addQueueItems Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::addQueueItems Sep 14 13:48:46 home---speakers volumio[1142]: info: Preload queue cleared Sep 14 13:48:46 home---speakers volumio[1142]: info: Adding Item to queue: http://stream.radioparadise.com/rock-flacm Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushQueue Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::saveQueue Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::updateTrackBlock Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrackBlock Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPlay Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::play index 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::stop Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::play index undefined Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::startPlaybackTimer Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: [1757872126771] ControllerWebradio::clearAddPlayTrack Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: Sep 14 13:48:46 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand stop took 12 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand stop took 7 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand status took 0 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:46 home---speakers volumio[1142]: info: Sep 14 13:48:46 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:46 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:46 home---speakers volumio[1142]: info: Sep 14 13:48:46 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:46 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:46 home---speakers volumio[1142]: info: Sep 14 13:48:46 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:46 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand clear took 4 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand status took 3 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: info: sendMpdCommand status took 2 milliseconds Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm" Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:46 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:46 home---speakers volumio[1142]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 14 13:48:46 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:46 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:46 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:46 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:46 home---speakers volumio[1142]: info: ------------------------------ 10ms Sep 14 13:48:46 home---speakers volumio[1142]: info: ------------------------------ 9ms Sep 14 13:48:46 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:48:46 home---speakers volumio[1142]: info: Sep 14 13:48:46 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:46 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:46 home---speakers volumio[1142]: info: Sep 14 13:48:46 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:46 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 217ms Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" Sep 14 13:48:47 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:48:47 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 212ms Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 211ms Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:47 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 1 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:47 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:47 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand play took 1 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 0ms Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 0ms Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 16 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 16 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 17 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 19ms Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 4 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 2 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 27ms Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 28ms Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 12ms Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 16ms Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 14ms Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:47 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:47 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:47 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:47 home---speakers volumio[1142]: info: Sep 14 13:48:47 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 2ms Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 2 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand status took 0 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 5ms Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:47 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:47 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:47 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 11ms Sep 14 13:48:47 home---speakers volumio[1142]: info: ------------------------------ 12ms Sep 14 13:48:49 home---speakers volumio[1142]: info: Sep 14 13:48:49 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:49 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:49 home---speakers volumio[1142]: info: Sep 14 13:48:49 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:49 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:49 home---speakers volumio[1142]: info: Sep 14 13:48:49 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:49 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:49 home---speakers volumio[1142]: info: Sep 14 13:48:49 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:49 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:49 home---speakers volumio[1142]: info: Sep 14 13:48:49 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:48:49 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:48:49 home---speakers volumio[1142]: info: Sep 14 13:48:49 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:49 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:49 home---speakers volumio[1142]: info: ------------------------------ 2ms Sep 14 13:48:49 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:49 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:48:49 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:49 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:48:49 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:49 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 13:48:49 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:49 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:49 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3369,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonic Impulse - Hits of Sunshine (For Allen Ginsberg)","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:49 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:49 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:49 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:49 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3369,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonic Impulse - Hits of Sunshine (For Allen Ginsberg)","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:49 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:49 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:49 home---speakers volumio[1142]: info: ------------------------------ 7ms Sep 14 13:48:49 home---speakers volumio[1142]: info: ------------------------------ 6ms Sep 14 13:48:49 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 13:48:49 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:49 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:49 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3369,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonic Impulse - Hits of Sunshine (For Allen Ginsberg)","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:48:49 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:48:49 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:49 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:49 home---speakers volumio[1142]: info: ------------------------------ 9ms Sep 14 13:48:55 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:55 home---speakers volumio[1142]: info: Listing playlists Sep 14 13:48:55 home---speakers volumio[1142]: info: Listing playlists Sep 14 13:48:58 home---speakers volumio[1142]: info: Sep 14 13:48:58 home---speakers volumio[1142]: ---------------------------- Client requests Volumio next Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::volumioNext Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::next Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::stop Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::stPlaybackTimer Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::updateTrackBlock Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrackBlock Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::serviceStop Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::serviceStop Sep 14 13:48:58 home---speakers volumio[1142]: info: [1757872138120] ControllerWebradio::stop Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand stop took 9 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::play index undefined Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 1 Sep 14 13:48:58 home---speakers volumio[1142]: info: Sep 14 13:48:58 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:58 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:58 home---speakers volumio[1142]: info: Sep 14 13:48:58 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:58 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:58 home---speakers volumio[1142]: info: Sep 14 13:48:58 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:48:58 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand status took 0 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:58 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:48:58 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:58 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:58 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:48:58 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:48:58 home---speakers volumio[1142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 14 13:48:58 home---speakers volumio[1142]: info: ------------------------------ 5ms Sep 14 13:48:58 home---speakers volumio[1142]: info: ------------------------------ 5ms Sep 14 13:48:58 home---speakers volumio[1142]: info: ------------------------------ 4ms Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- Client requests Volumio play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPlay Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::play index undefined Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::startPlaybackTimer Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: [1757872142309] ControllerWebradio::clearAddPlayTrack Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand stop took 3 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand clear took 0 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm" Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" Sep 14 13:49:02 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:49:02 home---speakers volumio[1142]: error: updateQueue error: null Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 163ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 163ms Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 2ms Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand play took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 14 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 14 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 14 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 19ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 21ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 20ms Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 13ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 12ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 12ms Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:02 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:02 home---speakers volumio[1142]: info: Sep 14 13:49:02 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 7ms Sep 14 13:49:02 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 14 13:49:02 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:02 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:02 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:02 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:02 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:02 home---speakers volumio[1142]: info: ------------------------------ 10ms Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetState Sep 14 13:49:05 home---speakers volumio[1142]: info: Sep 14 13:49:05 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:05 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:05 home---speakers volumio[1142]: info: Sep 14 13:49:05 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:05 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:05 home---speakers volumio[1142]: info: Sep 14 13:49:05 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:05 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:05 home---speakers volumio[1142]: info: Sep 14 13:49:05 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:05 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:05 home---speakers volumio[1142]: info: Sep 14 13:49:05 home---speakers volumio[1142]: ---------------------------- MPD announces system playlist update Sep 14 13:49:05 home---speakers volumio[1142]: info: Ignoring MPD Status Update Sep 14 13:49:05 home---speakers volumio[1142]: info: Sep 14 13:49:05 home---speakers volumio[1142]: ---------------------------- MPD announces state update: player Sep 14 13:49:05 home---speakers volumio[1142]: info: ControllerMpd::getState Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Sep 14 13:49:05 home---speakers volumio[1142]: info: ------------------------------ 2ms Sep 14 13:49:05 home---speakers volumio[1142]: info: sendMpdCommand status took 2 milliseconds Sep 14 13:49:05 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:05 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:05 home---speakers volumio[1142]: info: ------------------------------ 1ms Sep 14 13:49:05 home---speakers volumio[1142]: info: sendMpdCommand status took 1 milliseconds Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::parseState Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 13:49:05 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:49:05 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:49:05 home---speakers volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:05 home---speakers volumio[1142]: verbose: ControllerMpd::parseTrackInfo Sep 14 13:49:05 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:05 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3461,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonic Impulse - Hits of Sunshine (For Allen Ginsberg)","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:05 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:05 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:05 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:05 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3461,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonic Impulse - Hits of Sunshine (For Allen Ginsberg)","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:05 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:05 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:05 home---speakers volumio[1142]: info: ControllerMpd::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::servicePushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CorePlayQueue::getTrack 0 Sep 14 13:49:05 home---speakers volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3461,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sonic Impulse - Hits of Sunshine (For Allen Ginsberg)","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Sep 14 13:49:05 home---speakers volumio[1142]: verbose: CURRENT POSITION 0 Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::syncState stateService play Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::syncState currentStatus play Sep 14 13:49:05 home---speakers volumio[1142]: info: Received an update from plugin. extracting info from payload Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreStateMachine::pushState Sep 14 13:49:05 home---speakers volumio[1142]: info: CoreCommandRouter::volumioPushState Sep 14 13:49:05 home---speakers volumio[1142]: info: ------------------------------ 10ms Sep 14 13:49:05 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:49:05 home---speakers volumio[1142]: info: ------------------------------ 8ms Sep 14 13:49:06 home---speakers volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 13:49:06 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 13:49:10 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 13:49:13 home---speakers volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 14 13:49:13 home---speakers systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 14 13:49:13 home---speakers dbus-daemon[900]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1461' (uid=0 pid=21562 comm="timedatectl show --property=NTPSynchronized --valu") Sep 14 13:49:13 home---speakers systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 14 13:49:14 home---speakers dbus-daemon[900]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 14 13:49:14 home---speakers systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 14 13:49:14 home---speakers setdatetime-helper.sh[21561]: Time is already synchronized. Sep 14 13:49:14 home---speakers systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 14 13:49:14 home---speakers systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 14 13:49:14 home---speakers volumio[1142]: Use XMLElement.setAttribute instead Sep 14 13:49:14 home---speakers volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 13:49:14 home---speakers volumio[1142]: TypeError: children[i].attr(...).value is not a function Sep 14 13:49:14 home---speakers volumio[1142]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Sep 14 13:49:14 home---speakers volumio[1142]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 14 13:49:14 home---speakers volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 13:49:14 home---speakers sudo[21579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-14 13:48' Sep 14 13:49:14 home---speakers sudo[21579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"