Feb 08 14:22:03 volumio-hi-res-audio volumio[1441]: info: Tunnel connection is inactive, restarting it Feb 08 14:22:03 volumio-hi-res-audio volumio[1441]: info: Starting Tunnel 1 Feb 08 14:22:03 volumio-hi-res-audio volumio[1441]: info: Starting Tunnel Connection Checker Feb 08 14:22:04 volumio-hi-res-audio sudo[15809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 08 14:22:04 volumio-hi-res-audio sudo[15809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:04 volumio-hi-res-audio sudo[15809]: pam_unix(sudo:session): session closed for user root Feb 08 14:22:04 volumio-hi-res-audio volumio[1441]: info: Remote SSH Started Feb 08 14:22:04 volumio-hi-res-audio autossh[15812]: port set to 0, monitoring disabled Feb 08 14:22:04 volumio-hi-res-audio autossh[15812]: starting ssh (count 1) Feb 08 14:22:04 volumio-hi-res-audio autossh[15812]: ssh child pid is 15815 Feb 08 14:22:04 volumio-hi-res-audio volumio[1441]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 08 14:22:04 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:04 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:04 volumio-hi-res-audio volumiossh-tunnel[15815]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Feb 08 14:22:04 volumio-hi-res-audio autossh[15812]: ssh exited prematurely with status 255; autossh exiting Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:04 volumio-hi-res-audio autossh[15822]: port set to 0, monitoring disabled Feb 08 14:22:04 volumio-hi-res-audio autossh[15822]: starting ssh (count 1) Feb 08 14:22:04 volumio-hi-res-audio autossh[15822]: ssh child pid is 15825 Feb 08 14:22:04 volumio-hi-res-audio volumiossh-tunnel[15825]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Feb 08 14:22:04 volumio-hi-res-audio autossh[15822]: ssh exited prematurely with status 255; autossh exiting Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:04 volumio-hi-res-audio autossh[15827]: port set to 0, monitoring disabled Feb 08 14:22:04 volumio-hi-res-audio autossh[15827]: starting ssh (count 1) Feb 08 14:22:04 volumio-hi-res-audio autossh[15827]: ssh child pid is 15830 Feb 08 14:22:04 volumio-hi-res-audio volumiossh-tunnel[15830]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Feb 08 14:22:04 volumio-hi-res-audio autossh[15827]: ssh exited prematurely with status 255; autossh exiting Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Feb 08 14:22:04 volumio-hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:05 volumio-hi-res-audio autossh[15832]: port set to 0, monitoring disabled Feb 08 14:22:05 volumio-hi-res-audio autossh[15832]: starting ssh (count 1) Feb 08 14:22:05 volumio-hi-res-audio autossh[15832]: ssh child pid is 15835 Feb 08 14:22:05 volumio-hi-res-audio volumiossh-tunnel[15835]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Feb 08 14:22:05 volumio-hi-res-audio autossh[15832]: ssh exited prematurely with status 255; autossh exiting Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:05 volumio-hi-res-audio autossh[15840]: port set to 0, monitoring disabled Feb 08 14:22:05 volumio-hi-res-audio autossh[15840]: starting ssh (count 1) Feb 08 14:22:05 volumio-hi-res-audio autossh[15840]: ssh child pid is 15843 Feb 08 14:22:05 volumio-hi-res-audio volumiossh-tunnel[15843]: ssh: connect to host eu10.myvolumio.org port 2222: Connection refused Feb 08 14:22:05 volumio-hi-res-audio autossh[15840]: ssh exited prematurely with status 255; autossh exiting Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Start request repeated too quickly. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Feb 08 14:22:05 volumio-hi-res-audio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Preload queue cleared Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::ClearQueue Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stop Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::clearPlayQueue Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::saveQueue Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushQueue Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::addQueueItems Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::addQueueItems Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Preload queue cleared Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s307469 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushQueue Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::saveQueue Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::updateTrackBlock Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrackBlock Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPlay Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::play index 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stop Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::play index undefined Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::startPlaybackTimer Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetVisibleSources Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: [1770556937826] ControllerWebradio::clearAddPlayTrack Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand stop took 13 milliseconds Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand clear took 0 milliseconds Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand load "http://stream.104.6rtl.com/elektro/mp3-192/tunein/" Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: error: updateQueue error: null Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 1 milliseconds Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 1ms Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 5ms Feb 08 14:22:17 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=webradio volatile=false Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand add "http://stream.104.6rtl.com/elektro/mp3-192/tunein/" Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand add "http://stream.104.6rtl.com/elektro/mp3-192/tunein/" took 1 milliseconds Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand play Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 1ms Feb 08 14:22:18 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand play took 1 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 9 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 1 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: 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":"","artist":null,"album":null,"uri":"http://stream.104.6rtl.com/elektro/mp3-192/tunein/","trackType":""} Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus stop Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: 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":"","artist":null,"album":null,"uri":"http://stream.104.6rtl.com/elektro/mp3-192/tunein/","trackType":""} Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 15ms Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 13ms Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 4ms Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 3 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 1ms Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 0 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":722,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"104.6 RTL DJ Mix","artist":"104.6 RTL DJ Mix","album":null,"uri":"http://stream.104.6rtl.com/elektro/mp3-192/tunein/","trackType":""} Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 14ms Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":722,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"104.6 RTL DJ Mix","artist":"104.6 RTL DJ Mix","album":null,"uri":"http://stream.104.6rtl.com/elektro/mp3-192/tunein/","trackType":""} Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 26ms Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:19 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:21 volumio-hi-res-audio volumio[1441]: info: Executing endpoint metavolumio Feb 08 14:22:21 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 08 14:22:21 volumio-hi-res-audio volumio[1441]: info: Executing endpoint metavolumio Feb 08 14:22:21 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 08 14:22:23 volumio-hi-res-audio volumio[1441]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 14 Feb 08 14:22:23 volumio-hi-res-audio volumio[1441]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 14 Feb 08 14:22:23 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:23 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: Preload queue cleared Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::ClearQueue Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stPlaybackTimer Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::updateTrackBlock Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrackBlock Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::serviceStop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::serviceStop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: [1770556962805] ControllerWebradio::stop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::clearPlayQueue Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::saveQueue Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushQueue Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::addQueueItems Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::addQueueItems Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: Preload queue cleared Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s310383 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=webradio volatile=false Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand stop took 36 milliseconds Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 0 milliseconds Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: 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":"104.6 RTL DJ Mix","artist":"104.6 RTL DJ Mix","album":null,"uri":"http://stream.104.6rtl.com/elektro/mp3-192/tunein/","trackType":""} Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService stop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus stop Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: No code Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 25ms Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:42 volumio-hi-res-audio volumio[1441]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushQueue Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::saveQueue Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::updateTrackBlock Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrackBlock Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPlay Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::play index 0 Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stop Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::play index undefined Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::startPlaybackTimer Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetVisibleSources Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: [1770556963180] ControllerWebradio::clearAddPlayTrack Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand stop took 1 milliseconds Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand clear took 1 milliseconds Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand load "http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/" Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: error: updateQueue error: null Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 0ms Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand add "http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/" Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand add "http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/" took 0 milliseconds Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand play Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand play took 0 milliseconds Feb 08 14:22:43 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 0ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 9 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 0 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: 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":"","artist":null,"album":null,"uri":"http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/","trackType":""} Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus stop Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 10ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: 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":"","artist":null,"album":null,"uri":"http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/","trackType":""} Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 11ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Persist timer cancelled - playback resumed Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 3ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 1 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 1ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 1 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"104.6 RTL Dance Hits","artist":"104.6 RTL Dance Hits","album":null,"uri":"http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/","trackType":""} Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 12ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"104.6 RTL Dance Hits","artist":"104.6 RTL Dance Hits","album":null,"uri":"http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/","trackType":""} Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 20ms Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:44 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 1ms Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 1 milliseconds Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2029,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Purple Disco Machine & Kungs - Substitution","artist":"104.6 RTL Dance Hits","album":null,"uri":"http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/","trackType":""} Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 10ms Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:45 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:47 volumio-hi-res-audio volumio[1441]: info: Executing endpoint metavolumio Feb 08 14:22:47 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 08 14:22:47 volumio-hi-res-audio volumio[1441]: info: Executing endpoint metavolumio Feb 08 14:22:47 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: Preload queue cleared Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::ClearQueue Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stPlaybackTimer Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::updateTrackBlock Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrackBlock Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::serviceStop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::serviceStop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: [1770556968538] ControllerWebradio::stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::clearPlayQueue Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::saveQueue Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushQueue Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::addQueueItems Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::addQueueItems Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: Preload queue cleared Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s269136&filter=s:g2800:country Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=webradio volatile=false Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand stop took 26 milliseconds Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 0 milliseconds Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: 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":"Purple Disco Machine & Kungs - Substitution","artist":"104.6 RTL Dance Hits","album":null,"uri":"http://stream.104.6rtl.com/dance-hits/mp3-192/tunein/","trackType":""} Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: No code Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 23ms Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Starting persist timer - 15s Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushQueue Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::saveQueue Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::updateTrackBlock Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrackBlock Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPlay Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::play index 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::play index undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::startPlaybackTimer Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetVisibleSources Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: [1770556968885] ControllerWebradio::clearAddPlayTrack Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand stop took 1 milliseconds Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand clear took 0 milliseconds Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand load "https://qlink1.de/stream64" Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: error: updateQueue error: null Feb 08 14:22:48 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 0ms Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand load "https://qlink1.de/stream64" took 792 milliseconds Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand play Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand play took 1 milliseconds Feb 08 14:22:49 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 1ms Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 9 milliseconds Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 1 milliseconds Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: 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.weblygo.de:8030","artist":"320 FM","album":null,"uri":"https://stream.weblygo.de:8030","trackType":"de:8030"} Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus stop Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 13ms Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: 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.weblygo.de:8030","artist":"320 FM","album":null,"uri":"https://stream.weblygo.de:8030","trackType":"de:8030"} Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 11ms Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: Persist timer cancelled - playback resumed Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 12ms Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 12 milliseconds Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":721,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"stream.weblygo.de:8030","artist":"320 FM","album":null,"uri":"https://stream.weblygo.de:8030","trackType":"de:8030"} Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 22ms Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:50 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:52 volumio-hi-res-audio volumio[1441]: info: Executing endpoint metavolumio Feb 08 14:22:52 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 08 14:22:52 volumio-hi-res-audio volumio[1441]: info: Executing endpoint metavolumio Feb 08 14:22:52 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces system playlist update Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: Ignoring MPD Status Update Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: ---------------------------- MPD announces state update: player Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::getState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand status Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 0ms Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand status took 0 milliseconds Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: verbose: ControllerMpd::parseTrackInfo Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: ControllerMpd::pushState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::servicePushState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CorePlayQueue::getTrack 0 Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3698,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Nora En Pure - Purified Radio #493","artist":"320 FM","album":null,"uri":"https://stream.weblygo.de:8030","trackType":"de:8030"} Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: verbose: CURRENT POSITION 0 Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState stateService play Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::syncState currentStatus play Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: Received an update from plugin. extracting info from payload Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreStateMachine::pushState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioPushState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output update for this device Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: MRS: Pushing multiroomSync output Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: ------------------------------ 9ms Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:22:53 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Feb 08 14:23:02 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 08 14:23:02 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 08 14:23:06 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 08 14:23:09 volumio-hi-res-audio volumio[1441]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 13 Feb 08 14:23:09 volumio-hi-res-audio volumio[1441]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 13 Feb 08 14:23:09 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:23:09 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.conf.tmpl (isX64=false) Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Creating Spotify config file Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Preparing to generate the ALSA configuration file Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Reading ALSA contributions from plugins. Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Spotify config file written Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Asound.conf file written Feb 08 14:23:15 volumio-hi-res-audio sudo[16390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 14:23:15 volumio-hi-res-audio sudo[16390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio sudo[16393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 08 14:23:15 volumio-hi-res-audio sudo[16393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio sudo[16393]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Output device has changed, restarting MPD Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Output device has changed, restarting Shairport Sync Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 14:23:15 volumio-hi-res-audio sudo[16402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 14:23:15 volumio-hi-res-audio sudo[16402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 14:23:15 volumio-hi-res-audio sudo[16404]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16399]: go-librespot daemon starting... Feb 08 14:23:15 volumio-hi-res-audio sudo[16402]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:15 volumio-hi-res-audio sudo[16404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio vtcs[2542]: [2026-02-08 14:23:15.670] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 08 14:23:15 volumio-hi-res-audio vtcs[2542]: [2026-02-08 14:23:15.670] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: QobuzConnect: setDeactiveState invoked Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: info: CoreCommandRouter::volumioGetState Feb 08 14:23:15 volumio-hi-res-audio sudo[16390]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=info msg="running go-librespot 0.6.2" Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=debug msg="app state loaded" Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 14:23:15 volumio-hi-res-audio sudo[16419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 08 14:23:15 volumio-hi-res-audio sudo[16419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 14:23:15 volumio-hi-res-audio sudo[16421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 08 14:23:15 volumio-hi-res-audio sudo[16421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: vtcs.service: Killing process 2548 (vtcs) with signal SIGKILL. Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: Error: socket hang up Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: at connResetException (node:internal/errors:720:14) Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: at Socket.emit (node:events:526:35) Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: code: 'ECONNRESET', Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: response: undefined Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: } Feb 08 14:23:15 volumio-hi-res-audio volumio[1441]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 14:23:15 volumio-hi-res-audio sudo[16430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 08 14:23:15 volumio-hi-res-audio sudo[16430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: vtcs.service: Deactivated successfully. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 08 14:23:15 volumio-hi-res-audio sudo[16430]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:15 volumio-hi-res-audio sudo[16419]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:15 volumio-hi-res-audio sudo[16421]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:15 volumio-hi-res-audio sudo[16443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 08 14:23:15 volumio-hi-res-audio sudo[16443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: mpd.service: Deactivated successfully. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: mpd.service: Consumed 41.323s CPU time. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 14:23:15 volumio-hi-res-audio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 08 14:23:15 volumio-hi-res-audio qobuz-connect[2457]: 20260208 14:23:15.842 [2457.2457] INFO SampleApp: Stopping Local configuration server Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 14:23:15 volumio-hi-res-audio go-librespot[16410]: time="2026-02-08T14:23:15+01:00" level=info msg="zeroconf server listening on port 39281" Feb 08 14:23:15 volumio-hi-res-audio sudo[16451]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 14:23:15 volumio-hi-res-audio sudo[16451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 14:23:15 volumio-hi-res-audio sudo[16451]: pam_unix(sudo:session): session closed for user root Feb 08 14:23:16 volumio-hi-res-audio sudo[16458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 14:22' Feb 08 14:23:16 volumio-hi-res-audio sudo[16458]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"