-- Logs begin at Fri 2024-07-12 07:45:05 UTC, end at Sun 2024-09-01 08:43:36 UTC. -- Sep 01 08:42:00 opera volumio[17223]: info: Sep 01 08:42:00 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:00 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:00 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:00 opera volumio[17223]: info: sendMpdCommand status took 0 milliseconds Sep 01 08:42:00 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:00 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:00 opera volumio[17223]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 08:42:00 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:00 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:00 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:00 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:00 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:00 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:00 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"http://isure6.stream.qqmusic.qq.com/F000002Ia38v19jJtF.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5339257C6FD97F9D0E1F145B9295FC51D42691F9CCBE7EC9D152947EAAA1733D48A87EACD2AAC730DBE0923827CFE07430D08C08AB77D00A&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:00 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:00 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:00 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:00 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:00 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:00 opera volumio[17223]: info: ------------------------------ 14ms Sep 01 08:42:00 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:00 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:00 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:00 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:00 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:00 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:42:01 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:42:01 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:42:01 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:42:01 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:42:01 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:42:01 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:42:01 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:01 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:01 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:42:01 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: error: updateQueue error: null Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:42:01 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:42:01 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:42:01 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:42:01 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:01 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:01 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:01 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:01 opera volumio[17223]: info: Sep 01 08:42:01 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:01 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:01 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:02 opera volumio[17223]: info: sendMpdCommand status took 120 milliseconds Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:02 opera volumio[17223]: info: Sep 01 08:42:02 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:02 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:02 opera volumio[17223]: info: Sep 01 08:42:02 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:02 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:02 opera volumio[17223]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 08:42:02 opera volumio[17223]: info: ------------------------------ 4ms Sep 01 08:42:02 opera volumio[17223]: info: sendMpdCommand status took 5 milliseconds Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:02 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:02 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:02 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"BIGBANG","album":"ガラガラ GO!!","uri":"http://isure6.stream.qqmusic.qq.com/F000004ExrXP1MtrH2.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=87E465B31295C99F2863F09FA30FADE963065B9E5B9C9D8C44FF3AE6B32670224F35719F5B6DD89908BCFF8121FB074551965AF3F7A22DFA&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:02 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:02 opera volumio[17223]: info: ------------------------------ 152ms Sep 01 08:42:02 opera volumio[17223]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 01 08:42:02 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:02 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:02 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:02 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"626 Kbps","isStreaming":false,"title":"Top Of The World","artist":"BIGBANG","album":"ガラガラ GO!!","uri":"http://isure6.stream.qqmusic.qq.com/F000004ExrXP1MtrH2.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=87E465B31295C99F2863F09FA30FADE963065B9E5B9C9D8C44FF3AE6B32670224F35719F5B6DD89908BCFF8121FB074551965AF3F7A22DFA&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:02 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:02 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:02 opera volumio[17223]: info: ------------------------------ 47ms Sep 01 08:42:02 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:02 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:02 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:02 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:02 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:02 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:02 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:02 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Carpenters%20With%20The%20Royal%20Philharmonic%20Orchestra/9fb42b91-a085-4b2f-81e7-e4c6aeebbbbd.jpg' Sep 01 08:42:14 opera volumio[17223]: info: Sep 01 08:42:14 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:14 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:14 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:14 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:42:14 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:14 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:14 opera volumio[17223]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 08:42:14 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:14 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:14 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"BIGBANG","album":"ガラガラ GO!!","uri":"http://isure6.stream.qqmusic.qq.com/F000004ExrXP1MtrH2.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=87E465B31295C99F2863F09FA30FADE963065B9E5B9C9D8C44FF3AE6B32670224F35719F5B6DD89908BCFF8121FB074551965AF3F7A22DFA&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:14 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:14 opera volumio[17223]: info: ------------------------------ 17ms Sep 01 08:42:14 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:14 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:14 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:42:14 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:42:14 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:14 opera volumio[17223]: info: Sep 01 08:42:14 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:14 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:14 opera volumio[17223]: error: updateQueue error: null Sep 01 08:42:14 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:14 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:42:14 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:42:14 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:42:14 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:14 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:15 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:15 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:15 opera volumio[17223]: info: Sep 01 08:42:15 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:15 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:15 opera volumio[17223]: info: sendMpdCommand status took 3 milliseconds Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 5ms Sep 01 08:42:15 opera volumio[17223]: info: sendMpdCommand status took 4 milliseconds Sep 01 08:42:15 opera volumio[17223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:15 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:15 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:15 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:15 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 16ms Sep 01 08:42:15 opera volumio[17223]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 08:42:15 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:15 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:15 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:15 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:15 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:15 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:15 opera volumio[17223]: info: ------------------------------ 30ms Sep 01 08:42:15 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:15 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:15 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:15 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:15 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:15 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:15 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:15 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BIGBANG/%E3%82%AC%E3%83%A9%E3%82%AC%E3%83%A9%20GO!!/be81160e-a402-4a8c-b5e7-854e93eef969.jpg' Sep 01 08:42:37 opera volumio[17223]: info: Sep 01 08:42:37 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:37 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:37 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:37 opera volumio[17223]: info: sendMpdCommand status took 0 milliseconds Sep 01 08:42:37 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:37 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:37 opera volumio[17223]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 08:42:37 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:37 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:37 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:37 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":61000,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"689 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:37 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:37 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:37 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:37 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:42:37 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:37 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:37 opera volumio[17223]: info: ------------------------------ 12ms Sep 01 08:42:37 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:37 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:37 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:37 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:37 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:37 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:37 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:46 opera volumio[17223]: info: Sep 01 08:42:46 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:46 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:46 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:46 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:42:46 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:46 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:46 opera volumio[17223]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 08:42:46 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:46 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:46 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:46 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:46 opera volumio[17223]: info: ------------------------------ 16ms Sep 01 08:42:46 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:46 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:46 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:42:46 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:42:46 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:46 opera volumio[17223]: info: Sep 01 08:42:46 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:46 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:46 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:42:46 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:42:46 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:42:46 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:42:46 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:42:46 opera volumio[17223]: error: updateQueue error: null Sep 01 08:42:46 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:47 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:47 opera volumio[17223]: info: sendMpdCommand status took 4 milliseconds Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:42:47 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:42:47 opera volumio[17223]: info: Sep 01 08:42:47 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:42:47 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:42:47 opera volumio[17223]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:47 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:47 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:47 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"414 Kbps","isStreaming":false,"title":"Top of the World","artist":"Zayde Wølf","album":"Golden Age","uri":"http://isure6.stream.qqmusic.qq.com/F000001DOyTd30jW80.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B2CA203EB29317EB2A8677C4205EA8B0C5940204FA3BE90E758148E12EE86267A5027302882A14C41CB64716CAC65CFDF9866C2BBD880BD4&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:47 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 22ms Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 18ms Sep 01 08:42:47 opera volumio[17223]: info: sendMpdCommand status took 13 milliseconds Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:42:47 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:47 opera volumio[17223]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 08:42:47 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:42:47 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:42:47 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:42:47 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"415 Kbps","isStreaming":false,"title":"Top of the World","artist":"Zayde Wølf","album":"Golden Age","uri":"http://isure6.stream.qqmusic.qq.com/F000001DOyTd30jW80.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B2CA203EB29317EB2A8677C4205EA8B0C5940204FA3BE90E758148E12EE86267A5027302882A14C41CB64716CAC65CFDF9866C2BBD880BD4&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:42:47 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:42:47 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:42:47 opera volumio[17223]: info: ------------------------------ 35ms Sep 01 08:42:47 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:47 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:47 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:42:47 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:42:47 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:42:47 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:12 opera volumio[17223]: info: Sep 01 08:43:12 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:12 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:12 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:12 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:12 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:12 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:12 opera volumio[17223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 08:43:12 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:12 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:12 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top of the World","artist":"Zayde Wølf","album":"Golden Age","uri":"http://isure6.stream.qqmusic.qq.com/F000001DOyTd30jW80.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B2CA203EB29317EB2A8677C4205EA8B0C5940204FA3BE90E758148E12EE86267A5027302882A14C41CB64716CAC65CFDF9866C2BBD880BD4&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:12 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:12 opera volumio[17223]: info: ------------------------------ 23ms Sep 01 08:43:12 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:12 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:12 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:12 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:12 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:12 opera volumio[17223]: info: Sep 01 08:43:12 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:12 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:12 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:12 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:12 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:12 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:12 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:12 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:12 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 0ms Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:13 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:13 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:13 opera volumio[17223]: info: Sep 01 08:43:13 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:13 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:13 opera volumio[17223]: info: sendMpdCommand status took 6 milliseconds Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 5ms Sep 01 08:43:13 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:13 opera volumio[17223]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 08:43:13 opera volumio[17223]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:13 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:13 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:13 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:13 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"409 Kbps","isStreaming":false,"title":"Top of the World","artist":"Zayde Wølf","album":"Golden Age","uri":"http://isure6.stream.qqmusic.qq.com/F000001DOyTd30jW80.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B2CA203EB29317EB2A8677C4205EA8B0C5940204FA3BE90E758148E12EE86267A5027302882A14C41CB64716CAC65CFDF9866C2BBD880BD4&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:13 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:13 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:13 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:13 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"409 Kbps","isStreaming":false,"title":"Top of the World","artist":"Zayde Wølf","album":"Golden Age","uri":"http://isure6.stream.qqmusic.qq.com/F000001DOyTd30jW80.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B2CA203EB29317EB2A8677C4205EA8B0C5940204FA3BE90E758148E12EE86267A5027302882A14C41CB64716CAC65CFDF9866C2BBD880BD4&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:13 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:13 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 55ms Sep 01 08:43:13 opera volumio[17223]: info: ------------------------------ 50ms Sep 01 08:43:13 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:13 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:13 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:13 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:13 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:13 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:13 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:13 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Zayde%20W%C3%B8lf/Golden%20Age/a3e3b698-4ff3-4b5e-b13b-deab4786c2bb.jpg' Sep 01 08:43:15 opera volumio[17223]: info: Sep 01 08:43:15 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:15 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:15 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:15 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:43:15 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:15 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:15 opera volumio[17223]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 08:43:15 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:15 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:15 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:15 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:15 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:15 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:15 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top of the World","artist":"Zayde Wølf","album":"Golden Age","uri":"http://isure6.stream.qqmusic.qq.com/F000001DOyTd30jW80.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B2CA203EB29317EB2A8677C4205EA8B0C5940204FA3BE90E758148E12EE86267A5027302882A14C41CB64716CAC65CFDF9866C2BBD880BD4&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:15 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:15 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:15 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:15 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:15 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:15 opera volumio[17223]: info: ------------------------------ 12ms Sep 01 08:43:15 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:15 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:15 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:15 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:15 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:15 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:16 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:16 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:16 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:16 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:16 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:16 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:16 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:16 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:16 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:16 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:16 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:16 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:16 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:16 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:16 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 0ms Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:16 opera volumio[17223]: info: Sep 01 08:43:16 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:16 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:16 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:17 opera volumio[17223]: info: Sep 01 08:43:17 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:17 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:17 opera volumio[17223]: info: Sep 01 08:43:17 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:17 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:17 opera volumio[17223]: info: Sep 01 08:43:17 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:17 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:17 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:17 opera volumio[17223]: info: ------------------------------ 4ms Sep 01 08:43:17 opera volumio[17223]: info: sendMpdCommand status took 3 milliseconds Sep 01 08:43:17 opera volumio[17223]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:17 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:17 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:17 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:17 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:17 opera volumio[17223]: info: ------------------------------ 20ms Sep 01 08:43:17 opera volumio[17223]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:17 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:17 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:17 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:17 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:17 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:17 opera volumio[17223]: info: ------------------------------ 52ms Sep 01 08:43:17 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:17 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:17 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:17 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:17 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:17 opera volumio[17223]: info: Sep 01 08:43:17 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:17 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:17 opera volumio[17223]: info: sendMpdCommand status took 0 milliseconds Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:17 opera volumio[17223]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 08:43:17 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:17 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:17 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:17 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Ace Hood","album":"DJ Khaled Presents Ace Hood Gutta (Explicit)","uri":"http://isure6.stream.qqmusic.qq.com/F000004KVA2m3MVHYB.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=71394A8CCFA796F562C2EEA818337FDA30258CC72D0CB4BFA8676AF7D39FA6DAAF5B55AF846DC411F14A351EB34324F4590897E8C8FD63FF&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:17 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:17 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:17 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:17 opera volumio[17223]: info: ------------------------------ 12ms Sep 01 08:43:17 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:17 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:17 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:17 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:17 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:17 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:18 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:18 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:18 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:18 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:18 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:18 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:18 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 0ms Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:18 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:18 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:18 opera volumio[17223]: info: Sep 01 08:43:18 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:18 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:18 opera volumio[17223]: info: sendMpdCommand status took 4 milliseconds Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:43:18 opera volumio[17223]: info: sendMpdCommand status took 3 milliseconds Sep 01 08:43:18 opera volumio[17223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:18 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:18 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:18 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"626 Kbps","isStreaming":false,"title":"Top Of The World","artist":"BIGBANG","album":"ガラガラ GO!!","uri":"http://isure6.stream.qqmusic.qq.com/F000004ExrXP1MtrH2.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=87E465B31295C99F2863F09FA30FADE963065B9E5B9C9D8C44FF3AE6B32670224F35719F5B6DD89908BCFF8121FB074551965AF3F7A22DFA&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:18 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:18 opera volumio[17223]: info: ------------------------------ 24ms Sep 01 08:43:18 opera volumio[17223]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 08:43:18 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:18 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:18 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:18 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":182,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"626 Kbps","isStreaming":false,"title":"Top Of The World","artist":"BIGBANG","album":"ガラガラ GO!!","uri":"http://isure6.stream.qqmusic.qq.com/F000004ExrXP1MtrH2.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=87E465B31295C99F2863F09FA30FADE963065B9E5B9C9D8C44FF3AE6B32670224F35719F5B6DD89908BCFF8121FB074551965AF3F7A22DFA&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:18 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:18 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:18 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:18 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:19 opera volumio[17223]: info: ------------------------------ 42ms Sep 01 08:43:19 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:19 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:19 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:19 opera volumio[17223]: info: Sep 01 08:43:19 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:19 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:19 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:19 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:43:19 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:19 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:19 opera volumio[17223]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 08:43:19 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:19 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:19 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"BIGBANG","album":"ガラガラ GO!!","uri":"http://isure6.stream.qqmusic.qq.com/F000004ExrXP1MtrH2.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=87E465B31295C99F2863F09FA30FADE963065B9E5B9C9D8C44FF3AE6B32670224F35719F5B6DD89908BCFF8121FB074551965AF3F7A22DFA&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:19 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:19 opera volumio[17223]: info: ------------------------------ 11ms Sep 01 08:43:19 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:19 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:19 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:19 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:19 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:19 opera volumio[17223]: info: Sep 01 08:43:19 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:19 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:19 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:19 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:19 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:19 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:19 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:19 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:19 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:20 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:20 opera volumio[17223]: info: sendMpdCommand status took 122 milliseconds Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:20 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:20 opera volumio[17223]: info: Sep 01 08:43:20 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:20 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:20 opera volumio[17223]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:20 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:20 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:20 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"http://isure6.stream.qqmusic.qq.com/F000002Ia38v19jJtF.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5339257C6FD97F9D0E1F145B9295FC51D42691F9CCBE7EC9D152947EAAA1733D48A87EACD2AAC730DBE0923827CFE07430D08C08AB77D00A&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:20 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 137ms Sep 01 08:43:20 opera volumio[17223]: info: sendMpdCommand status took 16 milliseconds Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:20 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:20 opera volumio[17223]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 01 08:43:20 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:20 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:20 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:20 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":179,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1509 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"http://isure6.stream.qqmusic.qq.com/F000002Ia38v19jJtF.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5339257C6FD97F9D0E1F145B9295FC51D42691F9CCBE7EC9D152947EAAA1733D48A87EACD2AAC730DBE0923827CFE07430D08C08AB77D00A&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:20 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:20 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:20 opera volumio[17223]: info: ------------------------------ 63ms Sep 01 08:43:20 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:20 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:20 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:20 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:20 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:20 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:20 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/Carpenters%20With%20The%20Royal%20Philharmonic%20Orchestra/9fb42b91-a085-4b2f-81e7-e4c6aeebbbbd.jpg' Sep 01 08:43:21 opera volumio[17223]: info: Sep 01 08:43:21 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:21 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:21 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:21 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:43:21 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:21 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:21 opera volumio[17223]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 08:43:21 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:21 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:21 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"Carpenters With The Royal Philharmonic Orchestra","uri":"http://isure6.stream.qqmusic.qq.com/F000002Ia38v19jJtF.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5339257C6FD97F9D0E1F145B9295FC51D42691F9CCBE7EC9D152947EAAA1733D48A87EACD2AAC730DBE0923827CFE07430D08C08AB77D00A&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:21 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:21 opera volumio[17223]: info: ------------------------------ 12ms Sep 01 08:43:21 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:21 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:21 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:21 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:21 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:21 opera volumio[17223]: info: Sep 01 08:43:21 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:21 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:21 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:21 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:21 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:21 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:21 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:21 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:21 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:21 opera volumio[17223]: info: Sep 01 08:43:21 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:21 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:21 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:21 opera volumio[17223]: info: Sep 01 08:43:21 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:21 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:22 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:22 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:22 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:22 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:22 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:22 opera volumio[17223]: info: sendMpdCommand status took 121 milliseconds Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:22 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:22 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:22 opera volumio[17223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:43:22 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:22 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:22 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:22 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"A Song For You","uri":"http://isure6.stream.qqmusic.qq.com/F000000r8rs80IMrQ4.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=10CF67A05D49832D9D4021B5C96DF260FD2EE60F43E2E8F8D9C2BB0DE22FB7F34BAFDE6BFFC29566CDFE7BFB7814516F7C21350FB35858E7&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:22 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 133ms Sep 01 08:43:22 opera volumio[17223]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:22 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:22 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:22 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":179,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"A Song For You","uri":"http://isure6.stream.qqmusic.qq.com/F000000r8rs80IMrQ4.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=10CF67A05D49832D9D4021B5C96DF260FD2EE60F43E2E8F8D9C2BB0DE22FB7F34BAFDE6BFFC29566CDFE7BFB7814516F7C21350FB35858E7&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:22 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:22 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 20ms Sep 01 08:43:22 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:22 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:22 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:22 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:22 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:22 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carpenters/A%20Song%20For%20You/d54ede71-bb62-4a26-ac40-40cd71bdd7cb.png' Sep 01 08:43:22 opera volumio[17223]: info: Sep 01 08:43:22 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:22 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:22 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:22 opera volumio[17223]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 08:43:22 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:22 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:22 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:22 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Carpenters","album":"A Song For You","uri":"http://isure6.stream.qqmusic.qq.com/F000000r8rs80IMrQ4.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=10CF67A05D49832D9D4021B5C96DF260FD2EE60F43E2E8F8D9C2BB0DE22FB7F34BAFDE6BFFC29566CDFE7BFB7814516F7C21350FB35858E7&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:22 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:22 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:22 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:22 opera volumio[17223]: info: ------------------------------ 11ms Sep 01 08:43:22 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:22 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:22 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:22 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:22 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:22 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:23 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:23 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:23 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:23 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:23 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:23 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:23 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 0ms Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 3ms Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:23 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:23 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:23 opera volumio[17223]: info: Sep 01 08:43:23 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:23 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:23 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:23 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:23 opera volumio[17223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:23 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:23 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:23 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Historia De Un Amor","artist":"Francis Goya","album":"Grand Collection","uri":"http://isure6.stream.qqmusic.qq.com/M500000wCiGk37balt.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=F6EA7E237660466F204A18A37848A9A073C0C550AEB1B2C2552C2FBE8720CDC149E283D2867A5A16E841FCD7641E5DE03F1B7F9E7B8E5A1C&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Sep 01 08:43:23 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 26ms Sep 01 08:43:23 opera volumio[17223]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 01 08:43:23 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:23 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:23 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:23 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":149,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Historia De Un Amor","artist":"Francis Goya","album":"Grand Collection","uri":"http://isure6.stream.qqmusic.qq.com/M500000wCiGk37balt.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=F6EA7E237660466F204A18A37848A9A073C0C550AEB1B2C2552C2FBE8720CDC149E283D2867A5A16E841FCD7641E5DE03F1B7F9E7B8E5A1C&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Sep 01 08:43:23 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:23 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:23 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:23 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:23 opera volumio[17223]: info: ------------------------------ 49ms Sep 01 08:43:23 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:23 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:23 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:24 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:24 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:24 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:24 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:24 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:24 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:24 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Francis%20Goya/Grand%20Collection/076893f3-00b6-443d-9a04-e2ced99726c9.jpg' Sep 01 08:43:27 opera volumio[17223]: info: Sep 01 08:43:27 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:27 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:27 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:27 opera volumio[17223]: info: sendMpdCommand status took 0 milliseconds Sep 01 08:43:27 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:27 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:27 opera volumio[17223]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 01 08:43:27 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:27 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:27 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:27 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:27 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:27 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:27 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Historia De Un Amor","artist":"Francis Goya","album":"Grand Collection","uri":"http://isure6.stream.qqmusic.qq.com/M500000wCiGk37balt.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=F6EA7E237660466F204A18A37848A9A073C0C550AEB1B2C2552C2FBE8720CDC149E283D2867A5A16E841FCD7641E5DE03F1B7F9E7B8E5A1C&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Sep 01 08:43:27 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:27 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:27 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:27 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:27 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:27 opera volumio[17223]: info: ------------------------------ 18ms Sep 01 08:43:27 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:27 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:27 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:27 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:27 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:27 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:28 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:28 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:28 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:28 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:28 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:28 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioGetState Sep 01 08:43:28 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 2ms Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:28 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:28 opera volumio[17223]: info: Sep 01 08:43:28 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:28 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:28 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:28 opera volumio[17223]: info: sendMpdCommand status took 4 milliseconds Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:28 opera volumio[17223]: info: sendMpdCommand status took 2 milliseconds Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 4ms Sep 01 08:43:28 opera volumio[17223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:28 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:28 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:28 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"LA REINE DE SABA 萨巴女王","artist":"Paul Mauriat","album":"The Ultimate Paul Mauriat","uri":"http://isure6.stream.qqmusic.qq.com/F000004XaqfC48Nbzc.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B311C8E9563C59316F6062742A15C2B8AF76EDAE5C5B481B5A7B1D0000FB7A3E0BAD3484E6D9AEDB6F3A2D480FC0E81503F20EF1DA7796D5&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:28 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 20ms Sep 01 08:43:28 opera volumio[17223]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 01 08:43:28 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:28 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:28 opera volumio[17223]: verbose: In UPNP mode Sep 01 08:43:28 opera volumio[17223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":186,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"866 Kbps","isStreaming":false,"title":"LA REINE DE SABA 萨巴女王","artist":"Paul Mauriat","album":"The Ultimate Paul Mauriat","uri":"http://isure6.stream.qqmusic.qq.com/F000004XaqfC48Nbzc.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B311C8E9563C59316F6062742A15C2B8AF76EDAE5C5B481B5A7B1D0000FB7A3E0BAD3484E6D9AEDB6F3A2D480FC0E81503F20EF1DA7796D5&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:28 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::syncState stateService play Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:28 opera volumio[17223]: info: Received an update from plugin. extracting info from payload Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:28 opera volumio[17223]: info: ------------------------------ 51ms Sep 01 08:43:28 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:28 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:28 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:28 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:28 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:28 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:28 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:29 opera volumio[17223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Paul%20Mauriat/The%20Ultimate%20Paul%20Mauriat/9af218f8-1ddc-44da-b479-493b8ce474e8.png' Sep 01 08:43:35 opera volumio[17223]: info: Sep 01 08:43:35 opera volumio[17223]: ---------------------------- MPD announces state update: player Sep 01 08:43:35 opera volumio[17223]: info: ControllerMpd::getState Sep 01 08:43:35 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 08:43:35 opera volumio[17223]: info: sendMpdCommand status took 1 milliseconds Sep 01 08:43:35 opera volumio[17223]: verbose: ControllerMpd::parseState Sep 01 08:43:35 opera volumio[17223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 08:43:35 opera volumio[17223]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 08:43:35 opera volumio[17223]: verbose: ControllerMpd::parseTrackInfo Sep 01 08:43:35 opera volumio[17223]: info: ControllerMpd::pushState Sep 01 08:43:35 opera volumio[17223]: info: CoreCommandRouter::servicePushState Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:35 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:35 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:35 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:35 opera volumio[17223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LA REINE DE SABA 萨巴女王","artist":"Paul Mauriat","album":"The Ultimate Paul Mauriat","uri":"http://isure6.stream.qqmusic.qq.com/F000004XaqfC48Nbzc.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=B311C8E9563C59316F6062742A15C2B8AF76EDAE5C5B481B5A7B1D0000FB7A3E0BAD3484E6D9AEDB6F3A2D480FC0E81503F20EF1DA7796D5&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 08:43:35 opera volumio[17223]: verbose: CURRENT POSITION 0 Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::syncState stateService stop Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::syncState currentStatus play Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::play index undefined Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::pushState Sep 01 08:43:35 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:35 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:35 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:35 opera volumio[17223]: info: CorePlayQueue::getTrack 0 Sep 01 08:43:35 opera volumio[17223]: info: ------------------------------ 23ms Sep 01 08:43:35 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:35 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:35 opera volumio[17223]: info: CoreCommandRouter::volumioGetQueue Sep 01 08:43:35 opera volumio[17223]: info: CoreStateMachine::getQueue Sep 01 08:43:35 opera volumio[17223]: info: CorePlayQueue::getQueue Sep 01 08:43:36 opera volumio[17223]: info: Clearing queue after UPNP request Sep 01 08:43:36 opera volumio[17223]: info: CoreStateMachine::ClearQueue Sep 01 08:43:36 opera volumio[17223]: info: CoreStateMachine::stop Sep 01 08:43:36 opera volumio[17223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 08:43:36 opera volumio[17223]: verbose: UNSET VOLATILE Sep 01 08:43:36 opera volumio[17223]: info: CorePlayQueue::clearPlayQueue Sep 01 08:43:36 opera volumio[17223]: info: CorePlayQueue::saveQueue Sep 01 08:43:36 opera volumio[17223]: info: CoreCommandRouter::volumioPushState Sep 01 08:43:36 opera volumio[17223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 08:43:36 opera volumio[17223]: info: CoreCommandRouter::volumioPushQueue Sep 01 08:43:36 opera volumio[17223]: info: Updating RAAT Signal Path Sep 01 08:43:36 opera volumio[17223]: info: Sep 01 08:43:36 opera volumio[17223]: ---------------------------- MPD announces system playlist update Sep 01 08:43:36 opera volumio[17223]: info: Ignoring MPD Status Update Sep 01 08:43:36 opera volumio[17223]: error: updateQueue error: null Sep 01 08:43:36 opera volumio[17223]: info: ------------------------------ 1ms Sep 01 08:43:36 opera volumio[17223]: info: Starting UPNP Playback Sep 01 08:43:36 opera volumio[17223]: info: Preparing playback through UPNP Sep 01 08:43:36 opera volumio[17223]: info: CoreCommandRouter::volumioGetState PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"