Mar 24 14:24:01 rpivolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 16. Mar 24 14:24:01 rpivolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:01 rpivolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:01 rpivolumio upmpdcli[2618]: Could not open config: /tmp/upmpdcli.conf Mar 24 14:24:01 rpivolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 14:24:01 rpivolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 14:24:05 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:24:05 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:05 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:24:05 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:24:09 rpivolumio nmbd[1094]: [2026/03/24 14:24:09.053329, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 24 14:24:09 rpivolumio nmbd[1094]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.62.25 for name WORKGROUP<1d>. Mar 24 14:24:09 rpivolumio nmbd[1094]: This response was from IP 192.168.62.20, reporting an IP address of 192.168.62.20. Mar 24 14:24:15 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:24:15 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:16 rpivolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 17. Mar 24 14:24:16 rpivolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:16 rpivolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:16 rpivolumio upmpdcli[2635]: Could not open config: /tmp/upmpdcli.conf Mar 24 14:24:16 rpivolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 14:24:16 rpivolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 14:24:21 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 24 14:24:21 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:24 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 24 14:24:24 rpivolumio volumio[1134]: info: TuneIn handleBrowseUri: tunein/location Mar 24 14:24:24 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:25 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:24:25 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:25 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:24:25 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:24:26 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 24 14:24:26 rpivolumio volumio[1134]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 Mar 24 14:24:26 rpivolumio volumio[1134]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 Mar 24 14:24:26 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:30 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 24 14:24:30 rpivolumio volumio[1134]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101244 Mar 24 14:24:30 rpivolumio volumio[1134]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101244 Mar 24 14:24:30 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:30 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:32 rpivolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 18. Mar 24 14:24:32 rpivolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:32 rpivolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:32 rpivolumio upmpdcli[2665]: Could not open config: /tmp/upmpdcli.conf Mar 24 14:24:32 rpivolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 14:24:32 rpivolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 14:24:35 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 24 14:24:35 rpivolumio volumio[1134]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101244&filter=s:popular Mar 24 14:24:35 rpivolumio volumio[1134]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101244&filter=s:popular Mar 24 14:24:35 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:24:35 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:35 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:36 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreStateMachine::ClearQueue Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreStateMachine::stop Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:36 rpivolumio volumio[1134]: info: CorePlayQueue::clearPlayQueue Mar 24 14:24:36 rpivolumio volumio[1134]: info: CorePlayQueue::saveQueue Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreStateMachine::addQueueItems Mar 24 14:24:36 rpivolumio volumio[1134]: info: CorePlayQueue::addQueueItems Mar 24 14:24:36 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:36 rpivolumio volumio[1134]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s130490&filter=s:popular Mar 24 14:24:36 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Mar 24 14:24:37 rpivolumio volumio[1134]: info: CorePlayQueue::saveQueue Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::updateTrackBlock Mar 24 14:24:37 rpivolumio volumio[1134]: info: CorePlayQueue::getTrackBlock Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPlay Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::play index 0 Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::stop Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::play index undefined Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:37 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:37 rpivolumio volumio[1134]: info: CoreStateMachine::startPlaybackTimer Mar 24 14:24:37 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:37 rpivolumio volumio[1134]: info: [1774355077108] ControllerWebradio::clearAddPlayTrack Mar 24 14:24:37 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 14:24:37 rpivolumio volumio[1134]: info: sendMpdCommand stop took 4 milliseconds Mar 24 14:24:37 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 14:24:37 rpivolumio volumio[1134]: info: Mar 24 14:24:37 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:37 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:37 rpivolumio volumio[1134]: info: sendMpdCommand clear took 1 milliseconds Mar 24 14:24:37 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "https://stream.vanillaradio.com:8012/stream" Mar 24 14:24:37 rpivolumio volumio[1134]: info: Mar 24 14:24:37 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:37 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:37 rpivolumio volumio[1134]: info: Mar 24 14:24:37 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:37 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:37 rpivolumio volumio[1134]: error: updateQueue error: null Mar 24 14:24:37 rpivolumio volumio[1134]: info: ------------------------------ 3ms Mar 24 14:24:38 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand add "https://stream.vanillaradio.com:8012/stream" Mar 24 14:24:38 rpivolumio volumio[1134]: error: updateQueue error: null Mar 24 14:24:38 rpivolumio volumio[1134]: error: updateQueue error: null Mar 24 14:24:38 rpivolumio volumio[1134]: info: Mar 24 14:24:38 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:38 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:38 rpivolumio volumio[1134]: info: ------------------------------ 1159ms Mar 24 14:24:38 rpivolumio volumio[1134]: info: ------------------------------ 1158ms Mar 24 14:24:38 rpivolumio volumio[1134]: info: sendMpdCommand add "https://stream.vanillaradio.com:8012/stream" took 1 milliseconds Mar 24 14:24:38 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 14:24:38 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Mar 24 14:24:38 rpivolumio volumio[1134]: info: Mar 24 14:24:38 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:38 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:38 rpivolumio volumio[1134]: info: Mar 24 14:24:38 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:38 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:38 rpivolumio volumio[1134]: info: ------------------------------ 3ms Mar 24 14:24:38 rpivolumio volumio[1134]: info: sendMpdCommand play took 2 milliseconds Mar 24 14:24:38 rpivolumio volumio[1134]: info: ------------------------------ 2ms Mar 24 14:24:38 rpivolumio volumio[1134]: info: ------------------------------ 2ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 13 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 14 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 14 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: 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://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 19ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 6 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 2 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 2 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: 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://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: 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://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 78ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 78ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 61 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 60 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 60 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: 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://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: 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://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: 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://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 160ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 157ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 156ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 156ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 153 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 4ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 4 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 3ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 3 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:39 rpivolumio volumio[1134]: info: Mar 24 14:24:39 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 8ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 7 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 6ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 5 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 5ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand status took 4 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 247ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 96ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 95ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 102 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 102 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 101 milliseconds Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:39 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:39 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 195ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 192ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: ------------------------------ 192ms Mar 24 14:24:39 rpivolumio volumio[1134]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 24 14:24:44 rpivolumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 24 14:24:44 rpivolumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 24 14:24:44 rpivolumio systemd[1]: welcome.service: Deactivated successfully. Mar 24 14:24:44 rpivolumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 14:24:44 rpivolumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 14:24:44 rpivolumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 14:24:44 rpivolumio welcome[2699]: Resolved ip:[1] 192.168.62.25 Mar 24 14:24:44 rpivolumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 14:24:44 rpivolumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:24:45 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:24:45 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:24:45 rpivolumio volumio[1134]: info: Received Get System Info Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 14:24:45 rpivolumio volumio[1134]: info: Discovery: Getting this device information Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 14:24:45 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 14:24:46 rpivolumio volumio5-onboarding[1495]: time=2026-03-24T14:24:46.280+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 24 14:24:47 rpivolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 19. Mar 24 14:24:47 rpivolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:47 rpivolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:24:47 rpivolumio upmpdcli[2711]: Could not open config: /tmp/upmpdcli.conf Mar 24 14:24:47 rpivolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 14:24:47 rpivolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 14:24:47 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::ClearQueue Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::stPlaybackTimer Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::updateTrackBlock Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrackBlock Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::serviceStop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::serviceStop Mar 24 14:24:47 rpivolumio volumio[1134]: info: [1774355087751] ControllerWebradio::stop Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::clearPlayQueue Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::saveQueue Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::addQueueItems Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::addQueueItems Mar 24 14:24:47 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:24:47 rpivolumio volumio[1134]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s208277&filter=s:popular Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 24 14:24:47 rpivolumio volumio[1134]: info: Mar 24 14:24:47 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand stop took 45 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:47 rpivolumio volumio[1134]: info: Mar 24 14:24:47 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:47 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:47 rpivolumio volumio[1134]: info: Mar 24 14:24:47 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:47 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand status took 5 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand status took 4 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand status took 4 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:47 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: No code Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: No code Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mar G Rock, Spiros Hamza - Out Of Touch (Mark Leon Remix)","artist":"Vanilla Radio Deep Flavors","album":null,"uri":"https://stream.vanillaradio.com:8012/stream","trackType":""} Mar 24 14:24:47 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: No code Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:47 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:47 rpivolumio volumio[1134]: info: ------------------------------ 89ms Mar 24 14:24:47 rpivolumio volumio[1134]: info: ------------------------------ 88ms Mar 24 14:24:47 rpivolumio volumio[1134]: info: ------------------------------ 87ms Mar 24 14:24:47 rpivolumio volumio[1134]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Mar 24 14:24:48 rpivolumio volumio[1134]: info: CorePlayQueue::saveQueue Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::updateTrackBlock Mar 24 14:24:48 rpivolumio volumio[1134]: info: CorePlayQueue::getTrackBlock Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPlay Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::play index 0 Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::stop Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::play index undefined Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:24:48 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:48 rpivolumio volumio[1134]: info: CoreStateMachine::startPlaybackTimer Mar 24 14:24:48 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:48 rpivolumio volumio[1134]: info: [1774355088124] ControllerWebradio::clearAddPlayTrack Mar 24 14:24:48 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 14:24:48 rpivolumio volumio[1134]: info: sendMpdCommand stop took 3 milliseconds Mar 24 14:24:48 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 14:24:48 rpivolumio volumio[1134]: info: Mar 24 14:24:48 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:48 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:48 rpivolumio volumio[1134]: info: sendMpdCommand clear took 1 milliseconds Mar 24 14:24:48 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "https://smooth.vanillaradio.com:8006/live" Mar 24 14:24:48 rpivolumio volumio[1134]: info: Mar 24 14:24:48 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:48 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:48 rpivolumio volumio[1134]: info: Mar 24 14:24:48 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:48 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:48 rpivolumio volumio[1134]: error: updateQueue error: null Mar 24 14:24:48 rpivolumio volumio[1134]: info: ------------------------------ 4ms Mar 24 14:24:49 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand add "https://smooth.vanillaradio.com:8006/live" Mar 24 14:24:49 rpivolumio volumio[1134]: error: updateQueue error: null Mar 24 14:24:49 rpivolumio volumio[1134]: error: updateQueue error: null Mar 24 14:24:49 rpivolumio volumio[1134]: info: ------------------------------ 1219ms Mar 24 14:24:49 rpivolumio volumio[1134]: info: ------------------------------ 1219ms Mar 24 14:24:49 rpivolumio volumio[1134]: info: Mar 24 14:24:49 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:49 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:49 rpivolumio volumio[1134]: info: sendMpdCommand add "https://smooth.vanillaradio.com:8006/live" took 2 milliseconds Mar 24 14:24:49 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 14:24:49 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Mar 24 14:24:49 rpivolumio volumio[1134]: info: Mar 24 14:24:49 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:49 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:49 rpivolumio volumio[1134]: info: Mar 24 14:24:49 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:49 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:49 rpivolumio volumio[1134]: info: ------------------------------ 4ms Mar 24 14:24:49 rpivolumio volumio[1134]: info: sendMpdCommand play took 3 milliseconds Mar 24 14:24:49 rpivolumio volumio[1134]: info: ------------------------------ 2ms Mar 24 14:24:49 rpivolumio volumio[1134]: info: ------------------------------ 1ms Mar 24 14:24:50 rpivolumio volumio[1134]: info: Mar 24 14:24:50 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:50 rpivolumio volumio[1134]: info: Mar 24 14:24:50 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:50 rpivolumio volumio[1134]: info: Mar 24 14:24:50 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand status took 17 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:50 rpivolumio volumio[1134]: info: Mar 24 14:24:50 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:50 rpivolumio volumio[1134]: info: Mar 24 14:24:50 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand status took 24 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand status took 23 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand status took 5 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: 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":"live","artist":null,"album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Mar 24 14:24:50 rpivolumio volumio[1134]: info: ------------------------------ 28ms Mar 24 14:24:50 rpivolumio volumio[1134]: info: Mar 24 14:24:50 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand status took 5 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: info: sendMpdCommand status took 2 milliseconds Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: 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":"live","artist":null,"album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:50 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: 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":"live","artist":null,"album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:50 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: 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":"live","artist":null,"album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:50 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:50 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:50 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 50ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 50ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 34ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: 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":"live","artist":null,"album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: 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":"live","artist":null,"album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 57ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 50ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 13ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand status took 11 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 7ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand status took 5 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 5ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand status took 9 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces system playlist update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Ignoring MPD Status Update Mar 24 14:24:51 rpivolumio volumio[1134]: info: Mar 24 14:24:51 rpivolumio volumio[1134]: ---------------------------- MPD announces state update: player Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::getState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 23ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand status took 21 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 14ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand status took 13 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 14ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand status took 13 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseState Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"www.vanillaradio.com","artist":"Vanilla Smooth Flavors","album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"www.vanillaradio.com","artist":"Vanilla Smooth Flavors","album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"www.vanillaradio.com","artist":"Vanilla Smooth Flavors","album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 65ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 59ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 58ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"www.vanillaradio.com","artist":"Vanilla Smooth Flavors","album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"www.vanillaradio.com","artist":"Vanilla Smooth Flavors","album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ControllerMpd::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::servicePushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"www.vanillaradio.com","artist":"Vanilla Smooth Flavors","album":null,"uri":"https://smooth.vanillaradio.com:8006/live","trackType":""} Mar 24 14:24:51 rpivolumio volumio[1134]: verbose: CURRENT POSITION 0 Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState stateService play Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Mar 24 14:24:51 rpivolumio volumio[1134]: info: Received an update from plugin. extracting info from payload Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 93ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 85ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: ------------------------------ 86ms Mar 24 14:24:51 rpivolumio volumio[1134]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 24 14:24:55 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:25:02 rpivolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 20. Mar 24 14:25:02 rpivolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:25:02 rpivolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 24 14:25:02 rpivolumio upmpdcli[2748]: Could not open config: /tmp/upmpdcli.conf Mar 24 14:25:02 rpivolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 24 14:25:02 rpivolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 24 14:25:03 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 14:25:03 rpivolumio volumio[1134]: info: CURURI: music-library Mar 24 14:25:03 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:25:04 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 14:25:04 rpivolumio volumio[1134]: info: CURURI: music-library/NAS Mar 24 14:25:05 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:25:05 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioGetState Mar 24 14:25:05 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:25:05 rpivolumio volumio[1134]: info: Listing playlists Mar 24 14:25:05 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 14:25:05 rpivolumio volumio[1134]: info: CURURI: music-library/NAS/Data2 Mar 24 14:25:05 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:25:05 rpivolumio volumio[1134]: info: Preloading song: music-library/NAS/Data2/Adorable Deep Mix Deep House, Soundeo Mixtape.mp3 Mar 24 14:25:05 rpivolumio volumio[1134]: info: Preloading song: music-library/NAS/Data2/Armin van Buuren's Top 30 Trance Songs.mp3 Mar 24 14:25:05 rpivolumio volumio[1134]: info: Preloading song: music-library/NAS/Data2/Imram - Saishvari (Full Album) (320 kbps).mp3 Mar 24 14:25:05 rpivolumio volumio[1134]: info: Exploding uri music-library/NAS/Data2/Adorable Deep Mix Deep House, Soundeo Mixtape.mp3 in service mpd Mar 24 14:25:05 rpivolumio volumio[1134]: info: ALBUMART /albumart?cacheid=849&web=//mega&path=%2Fmnt%2FNAS%2FData2%2FAdorable%20Deep%20Mix%20Deep%20House%2C%20Soundeo%20Mixtape.mp3&metadata=false Mar 24 14:25:05 rpivolumio volumio[1134]: info: URI /mnt/NAS/Data2/Adorable Deep Mix Deep House, Soundeo Mixtape.mp3 Mar 24 14:25:05 rpivolumio volumio[1134]: info: Exploding uri music-library/NAS/Data2/Armin van Buuren's Top 30 Trance Songs.mp3 in service mpd Mar 24 14:25:05 rpivolumio volumio[1134]: info: ALBUMART /albumart?cacheid=849&web=//mega&path=%2Fmnt%2FNAS%2FData2%2FArmin%20van%20Buuren's%20Top%2030%20Trance%20Songs.mp3&metadata=false Mar 24 14:25:05 rpivolumio volumio[1134]: info: URI /mnt/NAS/Data2/Armin van Buuren's Top 30 Trance Songs.mp3 Mar 24 14:25:05 rpivolumio volumio[1134]: info: Exploding uri music-library/NAS/Data2/Imram - Saishvari (Full Album) (320 kbps).mp3 in service mpd Mar 24 14:25:05 rpivolumio volumio[1134]: info: ALBUMART /albumart?cacheid=849&web=//mega&path=%2Fmnt%2FNAS%2FData2%2FImram%20-%20Saishvari%20(Full%20Album)%20(320%20kbps).mp3&metadata=false Mar 24 14:25:05 rpivolumio volumio[1134]: info: URI /mnt/NAS/Data2/Imram - Saishvari (Full Album) (320 kbps).mp3 Mar 24 14:25:08 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 14:25:08 rpivolumio volumio[1134]: info: CURURI: music-library/NAS/Data2/For Music player Mar 24 14:25:08 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:25:09 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::ClearQueue Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::stop Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::stPlaybackTimer Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::updateTrackBlock Mar 24 14:25:09 rpivolumio volumio[1134]: info: CorePlayQueue::getTrackBlock Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::pushState Mar 24 14:25:09 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushState Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::serviceStop Mar 24 14:25:09 rpivolumio volumio[1134]: info: CorePlayQueue::getTrack 0 Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreCommandRouter::serviceStop Mar 24 14:25:09 rpivolumio volumio[1134]: info: [1774355109915] ControllerWebradio::stop Mar 24 14:25:09 rpivolumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 14:25:09 rpivolumio volumio[1134]: info: CorePlayQueue::clearPlayQueue Mar 24 14:25:09 rpivolumio volumio[1134]: info: CorePlayQueue::saveQueue Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Mar 24 14:25:09 rpivolumio volumio[1134]: info: CoreStateMachine::addQueueItems Mar 24 14:25:09 rpivolumio volumio[1134]: info: CorePlayQueue::addQueueItems Mar 24 14:25:09 rpivolumio volumio[1134]: info: Preload queue cleared Mar 24 14:25:09 rpivolumio volumio[1134]: info: Adding Item to queue: music-library/NAS/Data2/For Music player/Adele - Greatest Hits [2012] Mar 24 14:25:09 rpivolumio volumio[1134]: info: Exploding uri music-library/NAS/Data2/For Music player/Adele - Greatest Hits [2012] in service mpd Mar 24 14:25:09 rpivolumio volumio[1134]: info: ALBUMART /albumart?cacheid=849&web=Adele/Greatest%20Hits%20%5BCD%5D/mega&path=%2Fmnt%2FNAS%2FData2%2FFor%20Music%20player%2FAdele%20-%20Greatest%20Hits%20%5B2012%5D%2FCD%2FAdele%20-%20Greatest%20Hits%20%5BCD%5D.cue&metadata=false Mar 24 14:25:09 rpivolumio volumio[1134]: info: URI /mnt/NAS/Data2/For Music player/Adele - Greatest Hits [2012]/CD/Adele - Greatest Hits [CD].cue Mar 24 14:25:09 rpivolumio volumio[1134]: info: ALBUMART /albumart?cacheid=849&web=Adele/Greatest%20Hits%20%5BCD%5D/mega&path=%2Fmnt%2FNAS%2FData2%2FFor%20Music%20player%2FAdele%20-%20Greatest%20Hits%20%5B2012%5D%2FCD%2FAdele%20-%20Greatest%20Hits%20%5BCD%5D.cue&metadata=false Mar 24 14:25:09 rpivolumio volumio[1134]: info: URI /mnt/NAS/Data2/For Music player/Adele - Greatest Hits [2012]/CD/Adele - Greatest Hits [CD].cue Mar 24 14:25:09 rpivolumio volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 14:25:09 rpivolumio volumio[1134]: Error: Unable to resolve or reject the same promise twice Mar 24 14:25:09 rpivolumio volumio[1134]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 24 14:25:09 rpivolumio volumio[1134]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 24 14:25:09 rpivolumio volumio[1134]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 24 14:25:09 rpivolumio volumio[1134]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 24 14:25:09 rpivolumio volumio[1134]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 24 14:25:09 rpivolumio volumio[1134]: at Socket.emit (node:events:514:28) Mar 24 14:25:09 rpivolumio volumio[1134]: at addChunk (node:internal/streams/readable:343:12) Mar 24 14:25:09 rpivolumio volumio[1134]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 24 14:25:09 rpivolumio volumio[1134]: at Readable.push (node:internal/streams/readable:253:10) Mar 24 14:25:09 rpivolumio volumio[1134]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 24 14:25:09 rpivolumio volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 14:25:10 rpivolumio sudo[2773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 14:24' Mar 24 14:25:10 rpivolumio sudo[2773]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"