Starting Live Log...
185.255.55.20 local addr 192.168.1.109 -> 
info: CoreCommandRouter::volumioGetState
info: CoreCommandRouter::volumioGetQueue
info: CoreStateMachine::getQueue
info: CorePlayQueue::getQueue
info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
104.248.85.171 local addr 192.168.1.109 -> 
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
5.39.184.12 local addr 192.168.1.109 -> 
Starting Cleanup of Temporary Directories...
systemd-tmpfiles-clean.service: Succeeded.
Started Cleanup of Temporary Directories.
info:
---------------------------- MPD announces state update: player
info: ControllerMpd::getState


info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CorePlayQueue::getTrack 30


info: CoreStateMachine::syncState   stateService stop
info: CoreStateMachine::syncState   currentStatus play
info: CoreStateMachine::play index undefined
info: CoreStateMachine::setConsumeUpdateService undefined
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 31
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CorePlayQueue::getTrack 31
info: CoreStateMachine::startPlaybackTimer
info: CorePlayQueue::getTrack 31
info: [youtube2-play] clearAddPlayTrack: youtube2/hE2Ira-Cwxo
info: ------------------------------ 19ms
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 31
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: touch_display: Setting screensaver timeout to 120 seconds.
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 31
info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
info: touch_display: Setting screensaver timeout to 120 seconds.


info:
---------------------------- MPD announces system playlist update
info: Ignoring MPD Status Update

error: updateQueue error: null
info: ------------------------------ 2ms

info:
---------------------------- MPD announces system playlist update
info: Ignoring MPD Status Update


Upnp client error: Error: This socket has been ended by the other party
Upnp client error: Error: This socket has been ended by the other party
info:
---------------------------- MPD announces system playlist update
info: Ignoring MPD Status Update
info: ------------------------------ 3ms
info: CoreStateMachine::setConsumeUpdateService mpd

info: ------------------------------ 4ms
info:
---------------------------- MPD announces state update: player
info: ControllerMpd::getState



info:
---------------------------- MPD announces state update: player
info: ControllerMpd::getState


info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 31


info: CoreStateMachine::syncState   stateService play
info: CoreStateMachine::syncState   currentStatus stop
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: ------------------------------ 34ms



info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 31


info: CoreStateMachine::syncState   stateService play
info: CoreStateMachine::syncState   currentStatus play
info: Received an update from plugin. extracting info from payload
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: ------------------------------ 46ms
info: touch_display: Setting screensaver timeout to 0 seconds.
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
info: CoreCommandRouter::volumioGetState
info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
info: CoreCommandRouter::volumioGetVisibleSources
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
info: Listing playlists
info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
info:
---------------------------- MPD announces state update: player
info: ControllerMpd::getState


info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CorePlayQueue::getTrack 31


info: CoreStateMachine::syncState   stateService stop
info: CoreStateMachine::syncState   currentStatus play
info: CoreStateMachine::play index undefined
info: CoreStateMachine::setConsumeUpdateService undefined
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 32
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CorePlayQueue::getTrack 32
info: CoreStateMachine::startPlaybackTimer
info: CorePlayQueue::getTrack 32
info: [youtube2-play] clearAddPlayTrack: youtube2/ft4jcPSLJfY
info: ------------------------------ 18ms
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 32
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: touch_display: Setting screensaver timeout to 120 seconds.
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 32
info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
info: touch_display: Setting screensaver timeout to 120 seconds.


info:
---------------------------- MPD announces system playlist update
info: Ignoring MPD Status Update

error: updateQueue error: null
info: ------------------------------ 2ms

info:
---------------------------- MPD announces system playlist update
info: Ignoring MPD Status Update


Upnp client error: Error: This socket has been ended by the other party
Upnp client error: Error: This socket has been ended by the other party
info:
---------------------------- MPD announces system playlist update
info: Ignoring MPD Status Update
info: ------------------------------ 4ms
info: CoreStateMachine::setConsumeUpdateService mpd

info: ------------------------------ 3ms
info:
---------------------------- MPD announces state update: player
info: ControllerMpd::getState



info:
---------------------------- MPD announces state update: player
info: ControllerMpd::getState


info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 32


info: CoreStateMachine::syncState   stateService play
info: CoreStateMachine::syncState   currentStatus stop
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: ------------------------------ 32ms



info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 32


info: CoreStateMachine::syncState   stateService play
info: CoreStateMachine::syncState   currentStatus play
info: Received an update from plugin. extracting info from payload
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: ------------------------------ 48ms
info: touch_display: Setting screensaver timeout to 0 seconds.


-- Logs begin at Thu 2019-02-14 10:11:59 UTC. --
Dec 17 13:38:16 volumio mpd[4721]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated
Dec 17 13:38:16 volumio mpd[4721]: config_file: config parameter "buffer_before_play" on line 88 is deprecated
Dec 17 13:38:16 volumio mpd[4721]: config_file: config parameter "id3v1_encoding" on line 102 is deprecated
Dec 17 13:38:16 volumio mpd[4721]: Dec 17 13:38 : exception: Failed to bind to '[::]:6600'
Dec 17 13:38:16 volumio mpd[4721]: Dec 17 13:38 : exception: nested: Failed to bind socket: Address already in use
Dec 17 13:38:16 volumio systemd[4710]: mpd.service: Main process exited, code=exited, status=1/FAILURE
Dec 17 13:38:16 volumio systemd[4710]: mpd.service: Failed with result 'exit-code'.
Dec 17 13:38:16 volumio systemd[4710]: Failed to start Music Player Daemon.
Dec 17 13:38:16 volumio systemd[4710]: Reached target Default.
Dec 17 13:38:16 volumio systemd[4710]: Startup finished in 717ms.
Dec 17 13:40:39 volumio volumio[709]: info:
Dec 17 13:40:39 volumio volumio[709]: ---------------------------- MPD announces state update: player
Dec 17 13:40:39 volumio volumio[709]: info: ControllerMpd::getState
Dec 17 13:40:39 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status
Dec 17 13:40:39 volumio volumio[709]: verbose: ControllerMpd::parseState
Dec 17 13:40:39 volumio volumio[709]: info: ControllerMpd::pushState
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::servicePushState
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:40:39 volumio volumio[709]: info: CorePlayQueue::getTrack 33
Dec 17 13:40:39 volumio volumio[709]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 17 13:40:39 volumio volumio[709]: verbose: CURRENT POSITION 33
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::syncState   stateService stop
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::syncState   currentStatus play
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::play index undefined
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:40:39 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:40:39 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer
Dec 17 13:40:39 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:40:39 volumio volumio[709]: info: [youtube2-play] clearAddPlayTrack: youtube2/QYh6mYIJG2Y
Dec 17 13:40:39 volumio volumio[709]: info: ------------------------------ 23ms
Dec 17 13:40:39 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:40:39 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:40:39 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:40:39 volumio volumio[709]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 17 13:40:40 volumio volumio[709]: info: CoreCommandRouter::volumioGetState
Dec 17 13:40:40 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:40:40 volumio volumio[709]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Dec 17 13:40:40 volumio volumio[709]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 17 13:41:06 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop
Dec 17 13:41:06 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand clear
Dec 17 13:41:06 volumio volumio[709]: info:
Dec 17 13:41:06 volumio volumio[709]: ---------------------------- MPD announces system playlist update
Dec 17 13:41:06 volumio volumio[709]: info: Ignoring MPD Status Update
Dec 17 13:41:06 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand load "https://r2---sn-5hne6n7z.googlevideo.com/videoplayback?expire=1608234060&ei=7F_bX7vXIZGo1wL4qYXABg&ip=92.110.49.158&id=o-AItm5cdTTYQ1pSkN3aTmYNK4WF3iiE32SJPjw-y58Xup&itag=251&source=youtube&requiressl=yes&mh=pJ&mm=31%2C26&mn=sn-5hne6n7z%2Csn-4g5e6nls&ms=au%2Conr&mv=m&mvi=2&pl=16&gcr=nl&initcwndbps=1841250&vprv=1&mime=audio%2Fwebm&ns=ya1ocKS9ZAQTxRzhVfRwk3QF&gir=yes&clen=3055229&dur=184.921&lmt=1580292746568913&mt=1608212208&fvip=2&keepalive=yes&c=WEB&txp=5531432&n=gk3alF83EMiQadQiN&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRgIhANKb7thHHw6wqybck_ua-ZXliihQTvjykEnGJjzyPrUyAiEA6vwV3D5PjuNtRRu-C6jSbD00OGGPW0BfYMzgt-8iRk0%3D&ratebypass=yes&sig=AOq0QJ8wRQIhALNZSBQaI5gLSyNjkU91fVzamEFe0ntUlVrv1ESEJo7_AiBj1RXmnXt2PvGJGzTapNyqt0cKx2mBJKjpMS_I89aHZw%3D%3D"
Dec 17 13:41:06 volumio volumio[709]: error: updateQueue error: null
Dec 17 13:41:06 volumio volumio[709]: info: ------------------------------ 3ms
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand addid "https://r2---sn-5hne6n7z.googlevideo.com/videoplayback?expire=1608234060&ei=7F_bX7vXIZGo1wL4qYXABg&ip=92.110.49.158&id=o-AItm5cdTTYQ1pSkN3aTmYNK4WF3iiE32SJPjw-y58Xup&itag=251&source=youtube&requiressl=yes&mh=pJ&mm=31%2C26&mn=sn-5hne6n7z%2Csn-4g5e6nls&ms=au%2Conr&mv=m&mvi=2&pl=16&gcr=nl&initcwndbps=1841250&vprv=1&mime=audio%2Fwebm&ns=ya1ocKS9ZAQTxRzhVfRwk3QF&gir=yes&clen=3055229&dur=184.921&lmt=1580292746568913&mt=1608212208&fvip=2&keepalive=yes&c=WEB&txp=5531432&n=gk3alF83EMiQadQiN&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRgIhANKb7thHHw6wqybck_ua-ZXliihQTvjykEnGJjzyPrUyAiEA6vwV3D5PjuNtRRu-C6jSbD00OGGPW0BfYMzgt-8iRk0%3D&ratebypass=yes&sig=AOq0QJ8wRQIhALNZSBQaI5gLSyNjkU91fVzamEFe0ntUlVrv1ESEJo7_AiBj1RXmnXt2PvGJGzTapNyqt0cKx2mBJKjpMS_I89aHZw%3D%3D"
Dec 17 13:41:12 volumio volumio[709]: Upnp client error: Error: This socket has been ended by the other party
Dec 17 13:41:12 volumio volumio[709]: info:
Dec 17 13:41:12 volumio volumio[709]: ---------------------------- MPD announces system playlist update
Dec 17 13:41:12 volumio volumio[709]: info: Ignoring MPD Status Update
Dec 17 13:41:12 volumio volumio[709]: verbose: MPD COMMAND [object Object]
Dec 17 13:41:12 volumio volumio[709]: verbose: MPD COMMAND [object Object]
Dec 17 13:41:12 volumio volumio[709]: info:
Dec 17 13:41:12 volumio volumio[709]: ---------------------------- MPD announces system playlist update
Dec 17 13:41:12 volumio volumio[709]: info: Ignoring MPD Status Update
Dec 17 13:41:12 volumio volumio[709]: info: ------------------------------ 3ms
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand play
Dec 17 13:41:12 volumio volumio[709]: info: ------------------------------ 3ms
Dec 17 13:41:12 volumio volumio[709]: info:
Dec 17 13:41:12 volumio volumio[709]: ---------------------------- MPD announces state update: player
Dec 17 13:41:12 volumio volumio[709]: info: ControllerMpd::getState
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::parseState
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 17 13:41:12 volumio volumio[709]: info:
Dec 17 13:41:12 volumio volumio[709]: ---------------------------- MPD announces state update: player
Dec 17 13:41:12 volumio volumio[709]: info: ControllerMpd::getState
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::parseState
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 17 13:41:12 volumio volumio[709]: info: ControllerMpd::pushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::servicePushState
Dec 17 13:41:12 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:41:12 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ariana Grande - 7 rings (Official Video)","artist":"Ariana Grande","album":null,"uri":"https://r2---sn-5hne6n7z.googlevideo.com/videoplayback?expire=1608234060&ei=7F_bX7vXIZGo1wL4qYXABg&ip=92.110.49.158&id=o-AItm5cdTTYQ1pSkN3aTmYNK4WF3iiE32SJPjw-y58Xup&itag=251&source=youtube&requiressl=yes&mh=pJ&mm=31%2C26&mn=sn-5hne6n7z%2Csn-4g5e6nls&ms=au%2Conr&mv=m&mvi=2&pl=16&gcr=nl&initcwndbps=1841250&vprv=1&mime=audio%2Fwebm&ns=ya1ocKS9ZAQTxRzhVfRwk3QF&gir=yes&clen=3055229&dur=184.921&lmt=1580292746568913&mt=1608212208&fvip=2&keepalive=yes&c=WEB&txp=5531432&n=gk3alF83EMiQadQiN&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRgIhANKb7thHHw6wqybck_ua-ZXliihQTvjykEnGJjzyPrUyAiEA6vwV3D5PjuNtRRu-C6jSbD00OGGPW0BfYMzgt-8iRk0%3D&ratebypass=yes&sig=AOq0QJ8wRQIhALNZSBQaI5gLSyNjkU91fVzamEFe0ntUlVrv1ESEJo7_AiBj1RXmnXt2PvGJGzTapNyqt0cKx2mBJKjpMS_I89aHZw%3D%3D","trackType":""}
Dec 17 13:41:12 volumio volumio[709]: verbose: CURRENT POSITION 34
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::syncState   stateService play
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::syncState   currentStatus stop
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:41:12 volumio volumio[709]: info: ------------------------------ 27ms
Dec 17 13:41:12 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo
Dec 17 13:41:12 volumio volumio[709]: info: ControllerMpd::pushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::servicePushState
Dec 17 13:41:12 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:41:12 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ariana Grande - 7 rings (Official Video)","artist":"Ariana Grande","album":null,"uri":"https://r2---sn-5hne6n7z.googlevideo.com/videoplayback?expire=1608234060&ei=7F_bX7vXIZGo1wL4qYXABg&ip=92.110.49.158&id=o-AItm5cdTTYQ1pSkN3aTmYNK4WF3iiE32SJPjw-y58Xup&itag=251&source=youtube&requiressl=yes&mh=pJ&mm=31%2C26&mn=sn-5hne6n7z%2Csn-4g5e6nls&ms=au%2Conr&mv=m&mvi=2&pl=16&gcr=nl&initcwndbps=1841250&vprv=1&mime=audio%2Fwebm&ns=ya1ocKS9ZAQTxRzhVfRwk3QF&gir=yes&clen=3055229&dur=184.921&lmt=1580292746568913&mt=1608212208&fvip=2&keepalive=yes&c=WEB&txp=5531432&n=gk3alF83EMiQadQiN&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRgIhANKb7thHHw6wqybck_ua-ZXliihQTvjykEnGJjzyPrUyAiEA6vwV3D5PjuNtRRu-C6jSbD00OGGPW0BfYMzgt-8iRk0%3D&ratebypass=yes&sig=AOq0QJ8wRQIhALNZSBQaI5gLSyNjkU91fVzamEFe0ntUlVrv1ESEJo7_AiBj1RXmnXt2PvGJGzTapNyqt0cKx2mBJKjpMS_I89aHZw%3D%3D","trackType":""}
Dec 17 13:41:12 volumio volumio[709]: verbose: CURRENT POSITION 34
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::syncState   stateService play
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::syncState   currentStatus play
Dec 17 13:41:12 volumio volumio[709]: info: Received an update from plugin. extracting info from payload
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:41:12 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:41:12 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:41:12 volumio volumio[709]: info: ------------------------------ 31ms
Dec 17 13:41:12 volumio volumio[709]: info: touch_display: Setting screensaver timeout to 0 seconds.
Dec 17 13:44:16 volumio volumio[709]: info:
Dec 17 13:44:16 volumio volumio[709]: ---------------------------- MPD announces state update: player
Dec 17 13:44:16 volumio volumio[709]: info: ControllerMpd::getState
Dec 17 13:44:16 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status
Dec 17 13:44:16 volumio volumio[709]: verbose: ControllerMpd::parseState
Dec 17 13:44:16 volumio volumio[709]: info: ControllerMpd::pushState
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::servicePushState
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:44:16 volumio volumio[709]: info: CorePlayQueue::getTrack 34
Dec 17 13:44:16 volumio volumio[709]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 17 13:44:16 volumio volumio[709]: verbose: CURRENT POSITION 34
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::syncState   stateService stop
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::syncState   currentStatus play
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::play index undefined
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:44:16 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:44:16 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::startPlaybackTimer
Dec 17 13:44:16 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:16 volumio volumio[709]: info: [youtube2-play] clearAddPlayTrack: youtube2/A9hcJgtnm6Q
Dec 17 13:44:16 volumio volumio[709]: info: ------------------------------ 14ms
Dec 17 13:44:16 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:44:16 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:44:16 volumio volumio[709]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 17 13:44:16 volumio volumio[709]: info: CoreCommandRouter::volumioGetState
Dec 17 13:44:16 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:16 volumio volumio[709]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Dec 17 13:44:16 volumio volumio[709]: info: touch_display: Setting screensaver timeout to 120 seconds.
Dec 17 13:44:42 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand stop
Dec 17 13:44:42 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand clear
Dec 17 13:44:42 volumio volumio[709]: info:
Dec 17 13:44:42 volumio volumio[709]: ---------------------------- MPD announces system playlist update
Dec 17 13:44:42 volumio volumio[709]: info: Ignoring MPD Status Update
Dec 17 13:44:42 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand load "https://r5---sn-5hne6nsy.googlevideo.com/videoplayback?expire=1608234277&ei=xWDbX4j0CYbW1wLj4prwDA&ip=92.110.49.158&id=o-ALra2Z4fpQyDp6ncLdz_wPtdzE5kPSP74v_TKGT7XFmb&itag=251&source=youtube&requiressl=yes&mh=lc&mm=31%2C26&mn=sn-5hne6nsy%2Csn-4g5edne6&ms=au%2Conr&mv=m&mvi=5&pl=16&gcr=nl&initcwndbps=1827500&vprv=1&mime=audio%2Fwebm&ns=8fbLKkRAoKXA5mMI-CREehkF&gir=yes&clen=3871876&dur=239.941&lmt=1582217512421637&mt=1608212208&fvip=5&keepalive=yes&c=WEB&txp=5531432&n=uTT4abb2AotiTavEU&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRQIhAJweRfUqAHFDTh7ysthvyL18DIrIusOc8xtZlcP1LFHRAiBM7kmIApw33YG-STFpkcOSfw2MYuEJxqmvMXirSQpyoA%3D%3D&ratebypass=yes&sig=AOq0QJ8wRAIgLzUPg8iJxa3vRlqV2YRNUai1yT4sH9EIg-ZMWh6pi0gCIDAh3rNMjY84888o2jeRc1ffOpR-ZWEP6m3UnjX6Xj_F"
Dec 17 13:44:42 volumio volumio[709]: error: updateQueue error: null
Dec 17 13:44:42 volumio volumio[709]: info: ------------------------------ 2ms
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand addid "https://r5---sn-5hne6nsy.googlevideo.com/videoplayback?expire=1608234277&ei=xWDbX4j0CYbW1wLj4prwDA&ip=92.110.49.158&id=o-ALra2Z4fpQyDp6ncLdz_wPtdzE5kPSP74v_TKGT7XFmb&itag=251&source=youtube&requiressl=yes&mh=lc&mm=31%2C26&mn=sn-5hne6nsy%2Csn-4g5edne6&ms=au%2Conr&mv=m&mvi=5&pl=16&gcr=nl&initcwndbps=1827500&vprv=1&mime=audio%2Fwebm&ns=8fbLKkRAoKXA5mMI-CREehkF&gir=yes&clen=3871876&dur=239.941&lmt=1582217512421637&mt=1608212208&fvip=5&keepalive=yes&c=WEB&txp=5531432&n=uTT4abb2AotiTavEU&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRQIhAJweRfUqAHFDTh7ysthvyL18DIrIusOc8xtZlcP1LFHRAiBM7kmIApw33YG-STFpkcOSfw2MYuEJxqmvMXirSQpyoA%3D%3D&ratebypass=yes&sig=AOq0QJ8wRAIgLzUPg8iJxa3vRlqV2YRNUai1yT4sH9EIg-ZMWh6pi0gCIDAh3rNMjY84888o2jeRc1ffOpR-ZWEP6m3UnjX6Xj_F"
Dec 17 13:44:48 volumio volumio[709]: info:
Dec 17 13:44:48 volumio volumio[709]: ---------------------------- MPD announces system playlist update
Dec 17 13:44:48 volumio volumio[709]: info: Ignoring MPD Status Update
Dec 17 13:44:48 volumio volumio[709]: verbose: MPD COMMAND [object Object]
Dec 17 13:44:48 volumio volumio[709]: Upnp client error: Error: This socket has been ended by the other party
Dec 17 13:44:48 volumio volumio[709]: Upnp client error: Error: This socket has been ended by the other party
Dec 17 13:44:48 volumio volumio[709]: info:
Dec 17 13:44:48 volumio volumio[709]: ---------------------------- MPD announces system playlist update
Dec 17 13:44:48 volumio volumio[709]: info: Ignoring MPD Status Update
Dec 17 13:44:48 volumio volumio[709]: info: ------------------------------ 3ms
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand play
Dec 17 13:44:48 volumio volumio[709]: info: ------------------------------ 3ms
Dec 17 13:44:48 volumio volumio[709]: info:
Dec 17 13:44:48 volumio volumio[709]: ---------------------------- MPD announces state update: player
Dec 17 13:44:48 volumio volumio[709]: info: ControllerMpd::getState
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status
Dec 17 13:44:48 volumio volumio[709]: info:
Dec 17 13:44:48 volumio volumio[709]: ---------------------------- MPD announces state update: player
Dec 17 13:44:48 volumio volumio[709]: info: ControllerMpd::getState
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand status
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::parseState
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::parseState
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo
Dec 17 13:44:48 volumio volumio[709]: info: ControllerMpd::pushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::servicePushState
Dec 17 13:44:48 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:48 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mark Ronson - Nothing Breaks Like a Heart (Official Video) ft. Miley Cyrus","artist":"Mark Ronson","album":null,"uri":"https://r5---sn-5hne6nsy.googlevideo.com/videoplayback?expire=1608234277&ei=xWDbX4j0CYbW1wLj4prwDA&ip=92.110.49.158&id=o-ALra2Z4fpQyDp6ncLdz_wPtdzE5kPSP74v_TKGT7XFmb&itag=251&source=youtube&requiressl=yes&mh=lc&mm=31%2C26&mn=sn-5hne6nsy%2Csn-4g5edne6&ms=au%2Conr&mv=m&mvi=5&pl=16&gcr=nl&initcwndbps=1827500&vprv=1&mime=audio%2Fwebm&ns=8fbLKkRAoKXA5mMI-CREehkF&gir=yes&clen=3871876&dur=239.941&lmt=1582217512421637&mt=1608212208&fvip=5&keepalive=yes&c=WEB&txp=5531432&n=uTT4abb2AotiTavEU&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRQIhAJweRfUqAHFDTh7ysthvyL18DIrIusOc8xtZlcP1LFHRAiBM7kmIApw33YG-STFpkcOSfw2MYuEJxqmvMXirSQpyoA%3D%3D&ratebypass=yes&sig=AOq0QJ8wRAIgLzUPg8iJxa3vRlqV2YRNUai1yT4sH9EIg-ZMWh6pi0gCIDAh3rNMjY84888o2jeRc1ffOpR-ZWEP6m3UnjX6Xj_F","trackType":""}
Dec 17 13:44:48 volumio volumio[709]: verbose: CURRENT POSITION 35
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::syncState   stateService play
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::syncState   currentStatus stop
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:44:48 volumio volumio[709]: info: ------------------------------ 21ms
Dec 17 13:44:48 volumio volumio[709]: verbose: ControllerMpd::parseTrackInfo
Dec 17 13:44:48 volumio volumio[709]: info: ControllerMpd::pushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::servicePushState
Dec 17 13:44:48 volumio volumio[709]: info: CorePlayQueue::getTrack 35
Dec 17 13:44:48 volumio volumio[709]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mark Ronson - Nothing Breaks Like a Heart (Official Video) ft. Miley Cyrus","artist":"Mark Ronson","album":null,"uri":"https://r5---sn-5hne6nsy.googlevideo.com/videoplayback?expire=1608234277&ei=xWDbX4j0CYbW1wLj4prwDA&ip=92.110.49.158&id=o-ALra2Z4fpQyDp6ncLdz_wPtdzE5kPSP74v_TKGT7XFmb&itag=251&source=youtube&requiressl=yes&mh=lc&mm=31%2C26&mn=sn-5hne6nsy%2Csn-4g5edne6&ms=au%2Conr&mv=m&mvi=5&pl=16&gcr=nl&initcwndbps=1827500&vprv=1&mime=audio%2Fwebm&ns=8fbLKkRAoKXA5mMI-CREehkF&gir=yes&clen=3871876&dur=239.941&lmt=1582217512421637&mt=1608212208&fvip=5&keepalive=yes&c=WEB&txp=5531432&n=uTT4abb2AotiTavEU&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cgcr%2Cvprv%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AG3C_xAwRQIhAJweRfUqAHFDTh7ysthvyL18DIrIusOc8xtZlcP1LFHRAiBM7kmIApw33YG-STFpkcOSfw2MYuEJxqmvMXirSQpyoA%3D%3D&ratebypass=yes&sig=AOq0QJ8wRAIgLzUPg8iJxa3vRlqV2YRNUai1yT4sH9EIg-ZMWh6pi0gCIDAh3rNMjY84888o2jeRc1ffOpR-ZWEP6m3UnjX6Xj_F","trackType":""}
Dec 17 13:44:48 volumio volumio[709]: verbose: CURRENT POSITION 35
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::syncState   stateService play
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::syncState   currentStatus play
Dec 17 13:44:48 volumio volumio[709]: info: Received an update from plugin. extracting info from payload
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:44:48 volumio volumio[709]: info: CoreStateMachine::pushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::volumioPushState
Dec 17 13:44:48 volumio volumio[709]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 13:44:48 volumio volumio[709]: info: ------------------------------ 25ms
Dec 17 13:44:48 volumio volumio[709]: info: touch_display: Setting screensaver timeout to 0 seconds.