-- Logs begin at Fri 2024-07-12 07:45:05 UTC, end at Sun 2024-09-01 12:57:34 UTC. -- Sep 01 12:56:12 opera volumio[3653]: info: Sep 01 12:56:12 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:12 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:12 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:12 opera volumio[3653]: info: sendMpdCommand status took 1 milliseconds Sep 01 12:56:12 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:12 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:12 opera volumio[3653]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 12:56:12 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:12 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:12 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:12 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:12 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:12 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:12 opera volumio[3653]: 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":"Romance (Arr. J. Williams for Guitar & Orchestra)","artist":"John Williams","album":"John Williams - The Guitarist","uri":"http://aqqmusic.tc.qq.com/M500004JdhXB45vvkY.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=82845BB0CBD87BB453484CD13BF69928594008720A6EBE8AF1E80BAAC5940601AB82AFC65934C32DFD56DDF28D23D067F5750AAE074F580A&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Sep 01 12:56:12 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::syncState stateService stop Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::play index undefined Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:12 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:12 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:12 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:12 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:12 opera volumio[3653]: info: ------------------------------ 18ms Sep 01 12:56:12 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:12 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:12 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:12 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:12 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:12 opera volumio[3653]: info: Clearing queue after UPNP request Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::ClearQueue Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::stop Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:56:13 opera volumio[3653]: verbose: UNSET VOLATILE Sep 01 12:56:13 opera volumio[3653]: info: CorePlayQueue::clearPlayQueue Sep 01 12:56:13 opera volumio[3653]: info: CorePlayQueue::saveQueue Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioPushQueue Sep 01 12:56:13 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: error: updateQueue error: null Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 2ms Sep 01 12:56:13 opera volumio[3653]: info: Starting UPNP Playback Sep 01 12:56:13 opera volumio[3653]: info: Preparing playback through UPNP Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioGetState Sep 01 12:56:13 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 0ms Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:13 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:13 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:13 opera volumio[3653]: info: sendMpdCommand status took 2 milliseconds Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:13 opera volumio[3653]: info: Sep 01 12:56:13 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:13 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:13 opera volumio[3653]: info: sendMpdCommand status took 3 milliseconds Sep 01 12:56:13 opera volumio[3653]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:13 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:13 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:56:13 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"893 Kbps","isStreaming":false,"title":"First Fool In Line","artist":"Don Williams","album":"And So It Goes","uri":"http://aqqmusic.tc.qq.com/F000003YOGzY3bTWGt.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=A5FFF20E6BA179CED9A687DBB925DE8A2AF38E960A8796D2DE7EAE55E076EAB3DB296D937D0A1B45762FA5CEBC1F6D305138F1F001D3D2B7&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:13 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus stop Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 15ms Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 11ms Sep 01 12:56:13 opera volumio[3653]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 12:56:13 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:13 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:13 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:56:13 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"893 Kbps","isStreaming":false,"title":"First Fool In Line","artist":"Don Williams","album":"And So It Goes","uri":"http://aqqmusic.tc.qq.com/F000003YOGzY3bTWGt.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=A5FFF20E6BA179CED9A687DBB925DE8A2AF38E960A8796D2DE7EAE55E076EAB3DB296D937D0A1B45762FA5CEBC1F6D305138F1F001D3D2B7&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:13 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:56:13 opera volumio[3653]: info: Received an update from plugin. extracting info from payload Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:13 opera volumio[3653]: info: ------------------------------ 27ms Sep 01 12:56:13 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:13 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:13 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:13 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:13 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:13 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:13 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:13 opera volumio[3653]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/John%20Williams/John%20Williams%20-%20The%20Guitarist/b8200965-de3c-4294-8b37-a780a5aabcd3.jpg' Sep 01 12:56:13 opera volumio[3653]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Don%20Williams/And%20So%20It%20Goes/b8ea7b90-d836-4e6d-9e99-f7bf53d778e5.jpg' Sep 01 12:56:40 opera volumio[3653]: info: Sep 01 12:56:40 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:40 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:40 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:40 opera volumio[3653]: info: sendMpdCommand status took 1 milliseconds Sep 01 12:56:40 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:40 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:40 opera volumio[3653]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 12:56:40 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:40 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:40 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:40 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:40 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:40 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:40 opera volumio[3653]: 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":"First Fool In Line","artist":"Don Williams","album":"And So It Goes","uri":"http://aqqmusic.tc.qq.com/F000003YOGzY3bTWGt.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=A5FFF20E6BA179CED9A687DBB925DE8A2AF38E960A8796D2DE7EAE55E076EAB3DB296D937D0A1B45762FA5CEBC1F6D305138F1F001D3D2B7&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:40 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::syncState stateService stop Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::play index undefined Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:40 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:40 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:40 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:40 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:40 opera volumio[3653]: info: ------------------------------ 20ms Sep 01 12:56:40 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:40 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:40 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:40 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:40 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:40 opera volumio[3653]: info: Clearing queue after UPNP request Sep 01 12:56:41 opera volumio[3653]: info: CoreStateMachine::ClearQueue Sep 01 12:56:41 opera volumio[3653]: info: CoreStateMachine::stop Sep 01 12:56:41 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:56:41 opera volumio[3653]: verbose: UNSET VOLATILE Sep 01 12:56:41 opera volumio[3653]: info: CorePlayQueue::clearPlayQueue Sep 01 12:56:41 opera volumio[3653]: info: CorePlayQueue::saveQueue Sep 01 12:56:41 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:41 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:41 opera volumio[3653]: info: CoreCommandRouter::volumioPushQueue Sep 01 12:56:41 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: error: updateQueue error: null Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Starting UPNP Playback Sep 01 12:56:41 opera volumio[3653]: info: Preparing playback through UPNP Sep 01 12:56:41 opera volumio[3653]: info: CoreCommandRouter::volumioGetState Sep 01 12:56:41 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:41 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:41 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:41 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:41 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:41 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:41 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:41 opera volumio[3653]: info: Sep 01 12:56:42 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:42 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:42 opera volumio[3653]: info: Sep 01 12:56:42 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:42 opera volumio[3653]: info: sendMpdCommand status took 2 milliseconds Sep 01 12:56:42 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:42 opera volumio[3653]: info: ------------------------------ 3ms Sep 01 12:56:42 opera volumio[3653]: info: sendMpdCommand status took 1 milliseconds Sep 01 12:56:42 opera volumio[3653]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:42 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:42 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:56:42 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23,"duration":348,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Les Patineurs","artist":"André Rieu","album":"Wiener Melange","uri":"http://aqqmusic.tc.qq.com/F000002KAgcC0Mc01J.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=E3B2CDFCB9F0AE862CB4F4381DE025CABEC748233AF250CE5D156AFC40DFB4ED41CD9E3D4BAB45195C7EE9737177987D29FA03F2EA85B2BC&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:42 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus stop Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:42 opera volumio[3653]: info: ------------------------------ 16ms Sep 01 12:56:42 opera volumio[3653]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 01 12:56:42 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:42 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:42 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:56:42 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":348,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"239 Kbps","isStreaming":false,"title":"Les Patineurs","artist":"André Rieu","album":"Wiener Melange","uri":"http://aqqmusic.tc.qq.com/F000002KAgcC0Mc01J.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=E3B2CDFCB9F0AE862CB4F4381DE025CABEC748233AF250CE5D156AFC40DFB4ED41CD9E3D4BAB45195C7EE9737177987D29FA03F2EA85B2BC&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:42 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:56:42 opera volumio[3653]: info: Received an update from plugin. extracting info from payload Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:42 opera volumio[3653]: info: ------------------------------ 35ms Sep 01 12:56:42 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:42 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:42 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:42 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:42 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:42 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:42 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:53 opera volumio[3653]: info: Sep 01 12:56:53 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:53 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:53 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:53 opera volumio[3653]: info: sendMpdCommand status took 1 milliseconds Sep 01 12:56:53 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:53 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:53 opera volumio[3653]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 01 12:56:53 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:53 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:53 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:53 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:53 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:53 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:53 opera volumio[3653]: 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":"Les Patineurs","artist":"André Rieu","album":"Wiener Melange","uri":"http://aqqmusic.tc.qq.com/F000002KAgcC0Mc01J.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=E3B2CDFCB9F0AE862CB4F4381DE025CABEC748233AF250CE5D156AFC40DFB4ED41CD9E3D4BAB45195C7EE9737177987D29FA03F2EA85B2BC&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:53 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::syncState stateService stop Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::play index undefined Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:53 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:53 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:53 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:53 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:53 opera volumio[3653]: info: ------------------------------ 12ms Sep 01 12:56:53 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:53 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:53 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:53 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:53 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:54 opera volumio[3653]: info: Clearing queue after UPNP request Sep 01 12:56:54 opera volumio[3653]: info: CoreStateMachine::ClearQueue Sep 01 12:56:54 opera volumio[3653]: info: CoreStateMachine::stop Sep 01 12:56:54 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:56:54 opera volumio[3653]: verbose: UNSET VOLATILE Sep 01 12:56:54 opera volumio[3653]: info: CorePlayQueue::clearPlayQueue Sep 01 12:56:54 opera volumio[3653]: info: CorePlayQueue::saveQueue Sep 01 12:56:54 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:54 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:54 opera volumio[3653]: info: CoreCommandRouter::volumioPushQueue Sep 01 12:56:54 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:54 opera volumio[3653]: info: Sep 01 12:56:54 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:54 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:54 opera volumio[3653]: error: updateQueue error: null Sep 01 12:56:54 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:54 opera volumio[3653]: info: Starting UPNP Playback Sep 01 12:56:54 opera volumio[3653]: info: Preparing playback through UPNP Sep 01 12:56:54 opera volumio[3653]: info: CoreCommandRouter::volumioGetState Sep 01 12:56:54 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:56:54 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 2ms Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 2ms Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:55 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:55 opera volumio[3653]: info: sendMpdCommand status took 122 milliseconds Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:56:55 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:56:55 opera volumio[3653]: info: Sep 01 12:56:55 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:56:55 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:56:55 opera volumio[3653]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 4ms Sep 01 12:56:55 opera volumio[3653]: info: sendMpdCommand status took 3 milliseconds Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:56:55 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:55 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:56:55 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1530 Kbps","isStreaming":false,"title":"For the Good Times","artist":"Perry Como","album":"Take It Easy With Perry Como","uri":"http://aqqmusic.tc.qq.com/F000001Ndq1b2Ozkw0.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=702C05034BD565DA889147A374C6C00FB1A22FAFD3A772A15A6109C5F6CCE2822E100F9A105C4BD62232B999738BD59D6D34D3875D02EE93&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:55 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus stop Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 141ms Sep 01 12:56:55 opera volumio[3653]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 01 12:56:55 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:56:55 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:56:55 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:56:55 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1583 Kbps","isStreaming":false,"title":"For the Good Times","artist":"Perry Como","album":"Take It Easy With Perry Como","uri":"http://aqqmusic.tc.qq.com/F000001Ndq1b2Ozkw0.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=702C05034BD565DA889147A374C6C00FB1A22FAFD3A772A15A6109C5F6CCE2822E100F9A105C4BD62232B999738BD59D6D34D3875D02EE93&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:56:55 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:56:55 opera volumio[3653]: info: Received an update from plugin. extracting info from payload Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:56:55 opera volumio[3653]: info: ------------------------------ 41ms Sep 01 12:56:55 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:55 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:55 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:55 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:55 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:56:55 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:56:55 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:56:55 opera volumio[3653]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Perry%20Como/Take%20It%20Easy%20With%20Perry%20Como/bd9e4c55-7528-483a-897c-d5027bdaa140.jpg' Sep 01 12:57:19 opera volumio[3653]: info: Sep 01 12:57:19 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:57:19 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:57:19 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:57:19 opera volumio[3653]: info: sendMpdCommand status took 1 milliseconds Sep 01 12:57:19 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:57:19 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:57:19 opera volumio[3653]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 12:57:19 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:57:19 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:57:19 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:57:19 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:57:19 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:57:19 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:57:19 opera volumio[3653]: 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":"For the Good Times","artist":"Perry Como","album":"Take It Easy With Perry Como","uri":"http://aqqmusic.tc.qq.com/F000001Ndq1b2Ozkw0.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=702C05034BD565DA889147A374C6C00FB1A22FAFD3A772A15A6109C5F6CCE2822E100F9A105C4BD62232B999738BD59D6D34D3875D02EE93&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:57:19 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::syncState stateService stop Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::play index undefined Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:57:19 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:57:19 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:57:19 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:57:19 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:57:19 opera volumio[3653]: info: ------------------------------ 18ms Sep 01 12:57:19 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:57:19 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:57:19 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:57:19 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:57:19 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:57:19 opera volumio[3653]: info: Clearing queue after UPNP request Sep 01 12:57:20 opera volumio[3653]: info: CoreStateMachine::ClearQueue Sep 01 12:57:20 opera volumio[3653]: info: CoreStateMachine::stop Sep 01 12:57:20 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 12:57:20 opera volumio[3653]: verbose: UNSET VOLATILE Sep 01 12:57:20 opera volumio[3653]: info: CorePlayQueue::clearPlayQueue Sep 01 12:57:20 opera volumio[3653]: info: CorePlayQueue::saveQueue Sep 01 12:57:20 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:57:20 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:57:20 opera volumio[3653]: info: CoreCommandRouter::volumioPushQueue Sep 01 12:57:20 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: error: updateQueue error: null Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:57:20 opera volumio[3653]: info: Starting UPNP Playback Sep 01 12:57:20 opera volumio[3653]: info: Preparing playback through UPNP Sep 01 12:57:20 opera volumio[3653]: info: CoreCommandRouter::volumioGetState Sep 01 12:57:20 opera volumio[3653]: info: CorePlayQueue::getTrack 0 Sep 01 12:57:20 opera volumio[3653]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 2ms Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:20 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:20 opera volumio[3653]: info: ------------------------------ 1ms Sep 01 12:57:20 opera volumio[3653]: info: Sep 01 12:57:20 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:57:20 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:57:20 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:57:21 opera volumio[3653]: info: sendMpdCommand status took 121 milliseconds Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:57:21 opera volumio[3653]: info: Sep 01 12:57:21 opera volumio[3653]: ---------------------------- MPD announces system playlist update Sep 01 12:57:21 opera volumio[3653]: info: Ignoring MPD Status Update Sep 01 12:57:21 opera volumio[3653]: info: Sep 01 12:57:21 opera volumio[3653]: ---------------------------- MPD announces state update: player Sep 01 12:57:21 opera volumio[3653]: info: ControllerMpd::getState Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand status Sep 01 12:57:21 opera volumio[3653]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:57:21 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:57:21 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:57:21 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"How Deep Is Your Love","artist":"Bee Gees","album":"Saturday Night Fever (The Original Movie Soundtrack) [Remastered]","uri":"http://aqqmusic.tc.qq.com/F000003qVV4E1rUFPw.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=9EF7D6C0C53D0A6CCA052236EB6ECFA6A47665A0D1181A9F13B0BB40F61B32E1153EE2313D984EF9FC1A260D7CB252204488C8AA192E538A&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:57:21 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus stop Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:57:21 opera volumio[3653]: info: ------------------------------ 138ms Sep 01 12:57:21 opera volumio[3653]: info: ------------------------------ 15ms Sep 01 12:57:21 opera volumio[3653]: info: sendMpdCommand status took 16 milliseconds Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::parseState Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 12:57:21 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:57:21 opera volumio[3653]: info: sendMpdCommand playlistinfo took 233 milliseconds Sep 01 12:57:21 opera volumio[3653]: verbose: ControllerMpd::parseTrackInfo Sep 01 12:57:21 opera volumio[3653]: info: ControllerMpd::pushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::servicePushState Sep 01 12:57:21 opera volumio[3653]: verbose: In UPNP mode Sep 01 12:57:21 opera volumio[3653]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"973 Kbps","isStreaming":false,"title":"How Deep Is Your Love","artist":"Bee Gees","album":"Saturday Night Fever (The Original Movie Soundtrack) [Remastered]","uri":"http://aqqmusic.tc.qq.com/F000003qVV4E1rUFPw.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=9EF7D6C0C53D0A6CCA052236EB6ECFA6A47665A0D1181A9F13B0BB40F61B32E1153EE2313D984EF9FC1A260D7CB252204488C8AA192E538A&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Sep 01 12:57:21 opera volumio[3653]: verbose: CURRENT POSITION 0 Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::syncState stateService play Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::syncState currentStatus play Sep 01 12:57:21 opera volumio[3653]: info: Received an update from plugin. extracting info from payload Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::pushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::volumioPushState Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 12:57:21 opera volumio[3653]: info: ------------------------------ 265ms Sep 01 12:57:21 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:57:21 opera volumio[3653]: info: Updating RAAT Signal Path Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:57:21 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:57:21 opera volumio[3653]: info: CoreCommandRouter::volumioGetQueue Sep 01 12:57:21 opera volumio[3653]: info: CoreStateMachine::getQueue Sep 01 12:57:21 opera volumio[3653]: info: CorePlayQueue::getQueue Sep 01 12:57:21 opera volumio[3653]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bee%20Gees/Saturday%20Night%20Fever%20(The%20Original%20Movie%20Soundtrack)%20%5BRemastered%5D/cbda0988-b659-4710-b1e3-45a5b63294b8.jpg' Sep 01 12:57:34 opera volumio[3653]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 12:57:34 opera volumio[3653]: { Error: connect ETIMEDOUT 103.226.246.99:443 Sep 01 12:57:34 opera volumio[3653]: at Object._errnoException (util.js:1022:11) Sep 01 12:57:34 opera volumio[3653]: at _exceptionWithHostPort (util.js:1044:20) Sep 01 12:57:34 opera volumio[3653]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 01 12:57:34 opera volumio[3653]: code: 'ETIMEDOUT', Sep 01 12:57:34 opera volumio[3653]: errno: 'ETIMEDOUT', Sep 01 12:57:34 opera volumio[3653]: syscall: 'connect', Sep 01 12:57:34 opera volumio[3653]: address: '103.226.246.99', Sep 01 12:57:34 opera volumio[3653]: port: 443 } Sep 01 12:57:34 opera volumio[3653]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 12:57:34 opera sudo[8999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 12:56 Sep 01 12:57:34 opera sudo[8999]: pam_unix(sudo:session): session opened for user root by (uid=0) 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"