-- Logs begin at Tue 2021-08-03 15:02:34 UTC, end at Sun 2025-05-18 13:45:26 UTC. -- May 18 13:44:01 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:05 music volumio[1257]: info: MyVolumio login type: Token May 18 13:44:05 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:09 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:09 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:09 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:09 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:09 music volumio[1257]: UNSET VOLATILE May 18 13:44:09 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:09 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:09 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:09 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:09 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:09 music volumio[1257]: info: May 18 13:44:09 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:09 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:09 music volumio[1257]: error: updateQueue error: null May 18 13:44:09 music volumio[1257]: info: ------------------------------ 4ms May 18 13:44:09 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:09 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:09 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:09 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:09 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:09 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:10 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:10 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:10 music volumio[1257]: info: sendMpdCommand status took 17 milliseconds May 18 13:44:10 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:10 music volumio[1257]: info: May 18 13:44:10 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:10 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:10 music volumio[1257]: info: sendMpdCommand status took 16 milliseconds May 18 13:44:10 music volumio[1257]: info: sendMpdCommand playlistinfo took 17 milliseconds May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:10 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:10 music volumio[1257]: In UPNP mode May 18 13:44:10 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"焚情","artist":"黄凯芹","album":"没结果的一些感情","uri":"http://aqqmusic.tc.qq.com/amobile.music.tc.qq.com/M800004NUXEG3VHL4D.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=AB69CFDDCAC4F06DC46DCA359B669299E4042608FA8B19B4528D1E9825EB2CD3A01F17BB2D1A956D510D0FA0FF7F3CD83223EA1E25852DB6__v21e2a1872&uin=36771&src=M800000B49Sy2moXgy.mp3&redirect=1&fromtag=101093","trackType":""} May 18 13:44:10 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:10 music volumio[1257]: info: ------------------------------ 62ms May 18 13:44:10 music volumio[1257]: info: ------------------------------ 30ms May 18 13:44:10 music volumio[1257]: info: sendMpdCommand playlistinfo took 19 milliseconds May 18 13:44:10 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:10 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:10 music volumio[1257]: In UPNP mode May 18 13:44:10 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"焚情","artist":"黄凯芹","album":"没结果的一些感情","uri":"http://aqqmusic.tc.qq.com/amobile.music.tc.qq.com/M800004NUXEG3VHL4D.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=AB69CFDDCAC4F06DC46DCA359B669299E4042608FA8B19B4528D1E9825EB2CD3A01F17BB2D1A956D510D0FA0FF7F3CD83223EA1E25852DB6__v21e2a1872&uin=36771&src=M800000B49Sy2moXgy.mp3&redirect=1&fromtag=101093","trackType":""} May 18 13:44:10 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:10 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:10 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:10 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:10 music volumio[1257]: info: ------------------------------ 82ms May 18 13:44:11 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:11 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:11 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:11 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:11 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:11 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:11 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:11 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:11 music volumio[1257]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 18 13:44:11 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:13 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:17 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:21 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:23 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:23 music volumio[1257]: UNSET VOLATILE May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:23 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:23 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:23 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:23 music volumio[1257]: info: May 18 13:44:23 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:23 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:23 music volumio[1257]: info: May 18 13:44:23 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:23 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:23 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:23 music volumio[1257]: info: sendMpdCommand stop took 87 milliseconds May 18 13:44:23 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:23 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:23 music volumio[1257]: error: updateQueue error: null May 18 13:44:23 music volumio[1257]: info: ------------------------------ 14ms May 18 13:44:23 music volumio[1257]: info: sendMpdCommand status took 11 milliseconds May 18 13:44:23 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:23 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:23 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:23 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:23 music volumio[1257]: info: No code May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:23 music volumio[1257]: info: ------------------------------ 49ms May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:23 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:23 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:23 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:24 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: ------------------------------ 1ms May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: ------------------------------ 1ms May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:24 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:24 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:24 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:24 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:24 music volumio[1257]: info: May 18 13:44:24 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:24 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:24 music volumio[1257]: info: sendMpdCommand status took 6 milliseconds May 18 13:44:24 music volumio[1257]: info: ------------------------------ 4ms May 18 13:44:24 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:24 music volumio[1257]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 13:44:24 music volumio[1257]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:24 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:24 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:24 music volumio[1257]: In UPNP mode May 18 13:44:24 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Im Krapfenwald'l Polka, Op. 336","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800004MTvfa4azxUv.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=CD7DA2F2AEFAA567D284F3B9AEA89C326EF11C67076DD51F7F72D4CB70C24AC623AC9C7CB71116A5A5C1715DF5C03371C4CC971F414BABB9__v21e2a1874&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:24 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:24 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:24 music volumio[1257]: In UPNP mode May 18 13:44:24 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Im Krapfenwald'l Polka, Op. 336","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800004MTvfa4azxUv.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=CD7DA2F2AEFAA567D284F3B9AEA89C326EF11C67076DD51F7F72D4CB70C24AC623AC9C7CB71116A5A5C1715DF5C03371C4CC971F414BABB9__v21e2a1874&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:24 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:24 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:24 music volumio[1257]: info: ------------------------------ 48ms May 18 13:44:24 music volumio[1257]: info: ------------------------------ 43ms May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:24 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:24 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:24 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:24 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:24 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:25 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:28 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:28 music volumio[1257]: UNSET VOLATILE May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:28 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:28 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:28 music volumio[1257]: info: May 18 13:44:28 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:28 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:28 music volumio[1257]: info: May 18 13:44:28 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:28 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:28 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:28 music volumio[1257]: info: sendMpdCommand stop took 90 milliseconds May 18 13:44:28 music volumio[1257]: error: updateQueue error: null May 18 13:44:28 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:28 music volumio[1257]: info: sendMpdCommand status took 3 milliseconds May 18 13:44:28 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:28 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:28 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:28 music volumio[1257]: info: No code May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:28 music volumio[1257]: info: ------------------------------ 27ms May 18 13:44:28 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:28 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:28 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:28 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:28 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:29 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:29 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:29 music volumio[1257]: info: sendMpdCommand status took 8 milliseconds May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:29 music volumio[1257]: info: May 18 13:44:29 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:29 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:29 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:29 music volumio[1257]: info: sendMpdCommand playlistinfo took 5 milliseconds May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:29 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:29 music volumio[1257]: In UPNP mode May 18 13:44:29 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:29 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:29 music volumio[1257]: info: ------------------------------ 40ms May 18 13:44:29 music volumio[1257]: info: sendMpdCommand status took 31 milliseconds May 18 13:44:29 music volumio[1257]: info: ------------------------------ 32ms May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:29 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:29 music volumio[1257]: info: sendMpdCommand playlistinfo took 54 milliseconds May 18 13:44:29 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:29 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:29 music volumio[1257]: In UPNP mode May 18 13:44:29 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:29 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:29 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:29 music volumio[1257]: info: ------------------------------ 117ms May 18 13:44:29 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:29 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:30 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:30 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:30 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:30 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:30 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:33 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:35 music volumio[1257]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 18 13:44:35 music volumio[1257]: info: Retrying Login Due to Network Error May 18 13:44:37 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:40 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:40 music volumio[1257]: UNSET VOLATILE May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:40 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:40 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:40 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:40 music volumio[1257]: info: May 18 13:44:40 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:40 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:40 music volumio[1257]: info: May 18 13:44:40 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:40 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:40 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:40 music volumio[1257]: info: sendMpdCommand stop took 100 milliseconds May 18 13:44:40 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:40 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:40 music volumio[1257]: error: updateQueue error: null May 18 13:44:40 music volumio[1257]: info: ------------------------------ 8ms May 18 13:44:40 music volumio[1257]: info: sendMpdCommand status took 7 milliseconds May 18 13:44:40 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:40 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:40 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:40 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:40 music volumio[1257]: info: No code May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:40 music volumio[1257]: info: ------------------------------ 40ms May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:40 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:40 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:40 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:40 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:41 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:41 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:41 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:41 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:41 music volumio[1257]: info: May 18 13:44:41 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:41 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:41 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:41 music volumio[1257]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:41 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:41 music volumio[1257]: In UPNP mode May 18 13:44:41 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:41 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:41 music volumio[1257]: info: ------------------------------ 39ms May 18 13:44:41 music volumio[1257]: info: ------------------------------ 26ms May 18 13:44:41 music volumio[1257]: info: sendMpdCommand playlistinfo took 23 milliseconds May 18 13:44:41 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:41 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:41 music volumio[1257]: In UPNP mode May 18 13:44:41 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:41 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:41 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:41 music volumio[1257]: info: ------------------------------ 66ms May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:41 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:41 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:41 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:41 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:41 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:42 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:43 music volumio[1257]: UNSET VOLATILE May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:43 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:43 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:43 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:43 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:43 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:43 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:43 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:43 music volumio[1257]: info: sendMpdCommand stop took 82 milliseconds May 18 13:44:43 music volumio[1257]: error: updateQueue error: null May 18 13:44:43 music volumio[1257]: info: ------------------------------ 5ms May 18 13:44:43 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:43 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:43 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:43 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:43 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:43 music volumio[1257]: info: No code May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:43 music volumio[1257]: info: ------------------------------ 35ms May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:43 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:43 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:43 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:43 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:43 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:43 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: May 18 13:44:43 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:43 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:43 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:43 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:44 music volumio[1257]: info: May 18 13:44:44 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:44 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:44 music volumio[1257]: info: May 18 13:44:44 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:44 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:44 music volumio[1257]: info: May 18 13:44:44 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:44 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:44 music volumio[1257]: info: sendMpdCommand status took 5 milliseconds May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:44 music volumio[1257]: info: sendMpdCommand status took 5 milliseconds May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:44 music volumio[1257]: info: ------------------------------ 7ms May 18 13:44:44 music volumio[1257]: info: sendMpdCommand playlistinfo took 4 milliseconds May 18 13:44:44 music volumio[1257]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:44 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:44 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:44 music volumio[1257]: In UPNP mode May 18 13:44:44 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:44 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:44 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:44 music volumio[1257]: In UPNP mode May 18 13:44:44 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:44 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:44 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:44 music volumio[1257]: info: ------------------------------ 47ms May 18 13:44:44 music volumio[1257]: info: ------------------------------ 46ms May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:44 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:44 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:44 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:44 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:44 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:45 music volumio[1257]: info: MyVolumio login type: Token May 18 13:44:45 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:46 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:46 music volumio[1257]: UNSET VOLATILE May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:46 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:46 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:46 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:46 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:46 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:46 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:46 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:46 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:46 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:46 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:46 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:46 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:46 music volumio[1257]: info: May 18 13:44:46 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:46 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:46 music volumio[1257]: info: May 18 13:44:46 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:46 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:46 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:46 music volumio[1257]: info: sendMpdCommand stop took 59 milliseconds May 18 13:44:46 music volumio[1257]: error: updateQueue error: null May 18 13:44:46 music volumio[1257]: info: ------------------------------ 6ms May 18 13:44:46 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:46 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:46 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:46 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:46 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:47 music volumio[1257]: info: sendMpdCommand status took 7 milliseconds May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:47 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:47 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:47 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:47 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:47 music volumio[1257]: info: No code May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:47 music volumio[1257]: info: ------------------------------ 53ms May 18 13:44:47 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:47 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:47 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:47 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:47 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: ------------------------------ 4ms May 18 13:44:47 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:47 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:47 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:47 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:47 music volumio[1257]: info: May 18 13:44:47 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:47 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:47 music volumio[1257]: info: sendMpdCommand status took 3 milliseconds May 18 13:44:47 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:47 music volumio[1257]: info: sendMpdCommand status took 3 milliseconds May 18 13:44:47 music volumio[1257]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:47 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:47 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:47 music volumio[1257]: In UPNP mode May 18 13:44:47 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:47 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:47 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:47 music volumio[1257]: info: ------------------------------ 31ms May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:47 music volumio[1257]: UNSET VOLATILE May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:47 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:47 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:47 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:48 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:48 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:48 music volumio[1257]: info: sendMpdCommand playlistinfo took 91 milliseconds May 18 13:44:48 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:48 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:48 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":154,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Champagne Polka, Op. 211","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001nmIla1zHnvo.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=72EDEC9D806639B7187F2C52F675FB9427A8C2B3340253CED614B1AF58F051E401B3BE0ADA82D51685F8FBEDBEA35A4B225371AB524AC7E3__v2b9ab33c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:48 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:48 music volumio[1257]: info: ------------------------------ 124ms May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:48 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:48 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:48 music volumio[1257]: info: sendMpdCommand stop took 137 milliseconds May 18 13:44:48 music volumio[1257]: error: updateQueue error: null May 18 13:44:48 music volumio[1257]: info: ------------------------------ 7ms May 18 13:44:48 music volumio[1257]: info: sendMpdCommand status took 5 milliseconds May 18 13:44:48 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:48 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getTrack 4 May 18 13:44:48 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:48 music volumio[1257]: verbose: CURRENT POSITION 4 May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:48 music volumio[1257]: info: ------------------------------ 33ms May 18 13:44:48 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:48 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:48 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:48 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:48 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:48 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:48 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: ------------------------------ 1ms May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: May 18 13:44:48 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:48 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:48 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:48 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:49 music volumio[1257]: info: May 18 13:44:49 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:49 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:49 music volumio[1257]: info: sendMpdCommand status took 3 milliseconds May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:49 music volumio[1257]: info: May 18 13:44:49 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:49 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:49 music volumio[1257]: info: May 18 13:44:49 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:49 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:49 music volumio[1257]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:49 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:49 music volumio[1257]: In UPNP mode May 18 13:44:49 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Banditen Galop, Op. 378","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001yJYPa3d8p8c.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=D931812C34F8AE76C722ABDFB83D18750A32D9E5D6A5189169D0B14247B1150C3D4B8A2535AB0418567223A45F5F3022346DC92B6B174AD4__v2b9ab34c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:49 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:49 music volumio[1257]: info: ------------------------------ 31ms May 18 13:44:49 music volumio[1257]: info: ------------------------------ 26ms May 18 13:44:49 music volumio[1257]: info: sendMpdCommand status took 25 milliseconds May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:49 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:49 music volumio[1257]: info: sendMpdCommand playlistinfo took 39 milliseconds May 18 13:44:49 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:49 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:49 music volumio[1257]: In UPNP mode May 18 13:44:49 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Banditen Galop, Op. 378","artist":"Cincinnati Pops Orchestra","album":"Ein Straussfest: Blue Danube Waltz, Champagne Polka, Tales from The Vienna Woods and Other Favorites","uri":"http://aqqmusic.tc.qq.com/M800001yJYPa3d8p8c.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=D931812C34F8AE76C722ABDFB83D18750A32D9E5D6A5189169D0B14247B1150C3D4B8A2535AB0418567223A45F5F3022346DC92B6B174AD4__v2b9ab34c&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:49 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:49 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:49 music volumio[1257]: info: ------------------------------ 101ms May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:49 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:49 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:49 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:49 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:49 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:51 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:51 music volumio[1257]: UNSET VOLATILE May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:51 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:51 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:51 music volumio[1257]: info: May 18 13:44:51 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:51 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:51 music volumio[1257]: info: May 18 13:44:51 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:51 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:51 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:51 music volumio[1257]: info: sendMpdCommand stop took 66 milliseconds May 18 13:44:51 music volumio[1257]: error: updateQueue error: null May 18 13:44:51 music volumio[1257]: info: ------------------------------ 6ms May 18 13:44:51 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:51 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:51 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:51 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:51 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:51 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:51 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:51 music volumio[1257]: info: No code May 18 13:44:51 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:52 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:52 music volumio[1257]: info: ------------------------------ 31ms May 18 13:44:52 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:52 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:52 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:52 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:52 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:52 music volumio[1257]: info: sendMpdCommand status took 3 milliseconds May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:52 music volumio[1257]: info: May 18 13:44:52 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:52 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:52 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:52 music volumio[1257]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:52 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:52 music volumio[1257]: In UPNP mode May 18 13:44:52 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"人生何处不相逢","artist":"陈慧娴","album":"秋色","uri":"http://aqqmusic.tc.qq.com/M800000iA4Yl3KAiuH.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=4EDCE0829FD5CCC18FA1BE136A61F0F26BA21AB1145C4EA3721EE238F1FD2FC1234F74C51A0C46BD281FAE7A54DC06B6311344E946A72A84__v2b9ab33c&uin=36771&src=M800001qZSJs1fiUPm.mp3&redirect=1&fromtag=101093","trackType":""} May 18 13:44:52 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:52 music volumio[1257]: info: ------------------------------ 21ms May 18 13:44:52 music volumio[1257]: info: sendMpdCommand status took 16 milliseconds May 18 13:44:52 music volumio[1257]: info: ------------------------------ 18ms May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:52 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:52 music volumio[1257]: info: sendMpdCommand playlistinfo took 37 milliseconds May 18 13:44:52 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:52 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:52 music volumio[1257]: In UPNP mode May 18 13:44:52 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"人生何处不相逢","artist":"陈慧娴","album":"秋色","uri":"http://aqqmusic.tc.qq.com/M800000iA4Yl3KAiuH.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=4EDCE0829FD5CCC18FA1BE136A61F0F26BA21AB1145C4EA3721EE238F1FD2FC1234F74C51A0C46BD281FAE7A54DC06B6311344E946A72A84__v2b9ab33c&uin=36771&src=M800001qZSJs1fiUPm.mp3&redirect=1&fromtag=101093","trackType":""} May 18 13:44:52 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:52 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:52 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:52 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:53 music volumio[1257]: info: ------------------------------ 96ms May 18 13:44:53 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:53 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:53 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:53 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:53 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:53 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:53 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:53 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:57 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:44:57 music volumio[1257]: info: Clearing queue after UPNP request May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::ClearQueue May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::stop May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 13:44:58 music volumio[1257]: UNSET VOLATILE May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::stPlaybackTimer May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::updateTrackBlock May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrackBlock May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::serviceStop May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: info: ControllerMpd::stop May 18 13:44:58 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::clearPlayQueue May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::saveQueue May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioPushQueue May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:58 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:58 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:58 music volumio[1257]: info: sendMpdCommand stop took 60 milliseconds May 18 13:44:58 music volumio[1257]: error: updateQueue error: null May 18 13:44:58 music volumio[1257]: info: ------------------------------ 9ms May 18 13:44:58 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:58 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:58 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 13:44:58 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::syncState stateService stop May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:58 music volumio[1257]: info: No code May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:58 music volumio[1257]: info: ------------------------------ 46ms May 18 13:44:58 music volumio[1257]: info: Starting UPNP Playback May 18 13:44:58 music volumio[1257]: info: Preparing playback through UPNP May 18 13:44:58 music volumio[1257]: info: CoreCommandRouter::volumioGetState May 18 13:44:58 music volumio[1257]: info: CorePlayQueue::getTrack 0 May 18 13:44:58 music volumio[1257]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 13:44:58 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:58 music volumio[1257]: info: ------------------------------ 3ms May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:58 music volumio[1257]: info: May 18 13:44:58 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:58 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:58 music volumio[1257]: info: ------------------------------ 2ms May 18 13:44:59 music volumio[1257]: info: May 18 13:44:59 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:59 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:59 music volumio[1257]: info: May 18 13:44:59 music volumio[1257]: ---------------------------- MPD announces system playlist update May 18 13:44:59 music volumio[1257]: info: Ignoring MPD Status Update May 18 13:44:59 music volumio[1257]: info: May 18 13:44:59 music volumio[1257]: ---------------------------- MPD announces state update: player May 18 13:44:59 music volumio[1257]: info: sendMpdCommand status took 4 milliseconds May 18 13:44:59 music volumio[1257]: info: ControllerMpd::getState May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand status May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:59 music volumio[1257]: info: ------------------------------ 5ms May 18 13:44:59 music volumio[1257]: info: sendMpdCommand status took 6 milliseconds May 18 13:44:59 music volumio[1257]: info: sendMpdCommand playlistinfo took 6 milliseconds May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::parseState May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:59 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:59 music volumio[1257]: In UPNP mode May 18 13:44:59 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"深夜港湾","artist":"甄楚倩","album":"无伴的舞","uri":"http://aqqmusic.tc.qq.com/M800001WF2gw2AMK6G.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=D470B260B9A659C11D8F6810CC27CBD56916CE05BDC07E5BD69E7622E7A628709E1221589872B97A971C5BFB0B71433BE19E55D1199329A1__v21e2a1874&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:59 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::syncState currentStatus stop May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:59 music volumio[1257]: info: ------------------------------ 29ms May 18 13:44:59 music volumio[1257]: info: sendMpdCommand playlistinfo took 15 milliseconds May 18 13:44:59 music volumio[1257]: verbose: ControllerMpd::parseTrackInfo May 18 13:44:59 music volumio[1257]: info: ControllerMpd::pushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::servicePushState May 18 13:44:59 music volumio[1257]: In UPNP mode May 18 13:44:59 music volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"深夜港湾","artist":"甄楚倩","album":"无伴的舞","uri":"http://aqqmusic.tc.qq.com/M800001WF2gw2AMK6G.mp3?guid=15BC001E20A64BECB29576F1E212F8EC&vkey=D470B260B9A659C11D8F6810CC27CBD56916CE05BDC07E5BD69E7622E7A628709E1221589872B97A971C5BFB0B71433BE19E55D1199329A1__v21e2a1874&uin=36771&redirect=1&fromtag=101093","trackType":""} May 18 13:44:59 music volumio[1257]: verbose: CURRENT POSITION 0 May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::syncState stateService play May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::syncState currentStatus play May 18 13:44:59 music volumio[1257]: info: Received an update from plugin. extracting info from payload May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::pushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::volumioPushState May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 13:44:59 music volumio[1257]: info: ------------------------------ 49ms May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:59 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:59 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:59 music volumio[1257]: info: CoreCommandRouter::volumioGetQueue May 18 13:44:59 music volumio[1257]: info: CoreStateMachine::getQueue May 18 13:44:59 music volumio[1257]: info: CorePlayQueue::getQueue May 18 13:44:59 music volumio[1257]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 13:44:59 music volumio[1257]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E6%85%A7%E5%A8%B4/%E7%A7%8B%E8%89%B2/337302d0-ef35-4215-b0e0-97b0124fd40c.jpg' May 18 13:45:01 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:45:05 music volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 13:45:15 music volumio[1257]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 18 13:45:15 music volumio[1257]: info: Retrying Login Due to Network Error May 18 13:45:25 music volumio[1257]: info: MyVolumio login type: Token May 18 13:45:25 music volumio[1257]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 13:45:25 music volumio[1257]: { Error: connect ETIMEDOUT 162.125.80.3:443 May 18 13:45:25 music volumio[1257]: at Object._errnoException (util.js:1022:11) May 18 13:45:25 music volumio[1257]: at _exceptionWithHostPort (util.js:1044:20) May 18 13:45:25 music volumio[1257]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 18 13:45:25 music volumio[1257]: code: 'ETIMEDOUT', May 18 13:45:25 music volumio[1257]: errno: 'ETIMEDOUT', May 18 13:45:25 music volumio[1257]: syscall: 'connect', May 18 13:45:25 music volumio[1257]: address: '162.125.80.3', May 18 13:45:25 music volumio[1257]: port: 443 } May 18 13:45:25 music volumio[1257]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 13:45:26 music sudo[7808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 13:44 May 18 13:45:26 music sudo[7808]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"