-- Logs begin at Fri 2024-07-12 07:45:05 UTC, end at Sat 2025-04-12 14:11:25 UTC. -- Apr 12 14:10:05 opera volumio[28816]: info: Apr 12 14:10:05 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:05 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:05 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:05 opera volumio[28816]: info: sendMpdCommand status took 3 milliseconds Apr 12 14:10:05 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:05 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:05 opera volumio[28816]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 14:10:05 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:05 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:05 opera volumio[28816]: 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":"Ascendance","artist":"Lindsey Stirling","album":"Shatter Me (Deluxe)","uri":"http://aqqmusic.tc.qq.com/F000004d2rJ81BJANt.flac?guid=000000005b56b722000001937137c5ff&vkey=62FCE37E2C4A4D8D67F3FEF27799430DAEC2F3CF15047E7BFF85685F7684F55554E0002EA583EE4F7DFEE47356C644CBF843EB9BF54AED53__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:05 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::syncState stateService stop Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::play index undefined Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:05 opera volumio[28816]: info: ------------------------------ 14ms Apr 12 14:10:05 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:05 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:05 opera volumio[28816]: info: Clearing queue after UPNP request Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::ClearQueue Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::stop Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:10:05 opera volumio[28816]: verbose: UNSET VOLATILE Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::clearPlayQueue Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::saveQueue Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::volumioPushQueue Apr 12 14:10:05 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:05 opera volumio[28816]: info: Apr 12 14:10:05 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:05 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:05 opera volumio[28816]: error: updateQueue error: null Apr 12 14:10:05 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:05 opera volumio[28816]: info: Starting UPNP Playback Apr 12 14:10:05 opera volumio[28816]: info: Preparing playback through UPNP Apr 12 14:10:05 opera volumio[28816]: info: CoreCommandRouter::volumioGetState Apr 12 14:10:05 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:05 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 14:10:06 opera volumio[28816]: info: Apr 12 14:10:06 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:06 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:06 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:06 opera volumio[28816]: info: Apr 12 14:10:06 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:06 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:06 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:06 opera volumio[28816]: info: Apr 12 14:10:06 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:06 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:06 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:06 opera volumio[28816]: info: Apr 12 14:10:06 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:06 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:06 opera volumio[28816]: info: ------------------------------ 0ms Apr 12 14:10:06 opera volumio[28816]: info: Apr 12 14:10:06 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:06 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:06 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:06 opera volumio[28816]: info: Apr 12 14:10:06 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:06 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:06 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:07 opera volumio[28816]: info: Apr 12 14:10:07 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:07 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:07 opera volumio[28816]: info: sendMpdCommand status took 2 milliseconds Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:07 opera volumio[28816]: info: Apr 12 14:10:07 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:07 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:07 opera volumio[28816]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:07 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:07 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:10:07 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Downpour","artist":"Omar Akram","album":"Daytime Dreamer","uri":"http://aqqmusic.tc.qq.com/F0000048lR622XjbK1.flac?guid=000000005b56b722000001937137c5ff&vkey=8C1703527BD964D50A0A2840A327D0F60E4A9409317D420C92711BCB5F1DFA098109B83C7841250AD4893F3F51E7D7D6155F6F48A0E7A9FE__v21e2a1871&uin=1152921504950983260&src=F000002L6q3944zPO1.flac&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:07 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus stop Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:07 opera volumio[28816]: info: ------------------------------ 19ms Apr 12 14:10:07 opera volumio[28816]: info: sendMpdCommand status took 16 milliseconds Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:07 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:07 opera volumio[28816]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 14:10:07 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:07 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:07 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:10:07 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Downpour","artist":"Omar Akram","album":"Daytime Dreamer","uri":"http://aqqmusic.tc.qq.com/F0000048lR622XjbK1.flac?guid=000000005b56b722000001937137c5ff&vkey=8C1703527BD964D50A0A2840A327D0F60E4A9409317D420C92711BCB5F1DFA098109B83C7841250AD4893F3F51E7D7D6155F6F48A0E7A9FE__v21e2a1871&uin=1152921504950983260&src=F000002L6q3944zPO1.flac&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:07 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:10:07 opera volumio[28816]: info: Received an update from plugin. extracting info from payload Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:07 opera volumio[28816]: info: ------------------------------ 38ms Apr 12 14:10:07 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:07 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:07 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:07 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:07 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:07 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:07 opera volumio[28816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lindsey%20Stirling/Shatter%20Me%20(Deluxe)/30edb83c-82e5-424e-9e04-0b2b1a2993a4.jpg' Apr 12 14:10:07 opera volumio[28816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Omar%20Akram/Daytime%20Dreamer/7dd02067-06f1-48d8-aeca-189bf0736a7e.jpg' Apr 12 14:10:33 opera volumio[28816]: info: Apr 12 14:10:33 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:33 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:33 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:33 opera volumio[28816]: info: sendMpdCommand status took 1 milliseconds Apr 12 14:10:33 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:33 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:33 opera volumio[28816]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 14:10:33 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:33 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:33 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:33 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:33 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:33 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:33 opera volumio[28816]: 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":"Downpour","artist":"Omar Akram","album":"Daytime Dreamer","uri":"http://aqqmusic.tc.qq.com/F0000048lR622XjbK1.flac?guid=000000005b56b722000001937137c5ff&vkey=8C1703527BD964D50A0A2840A327D0F60E4A9409317D420C92711BCB5F1DFA098109B83C7841250AD4893F3F51E7D7D6155F6F48A0E7A9FE__v21e2a1871&uin=1152921504950983260&src=F000002L6q3944zPO1.flac&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:33 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::syncState stateService stop Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::play index undefined Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:33 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:33 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:33 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:33 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:33 opera volumio[28816]: info: ------------------------------ 14ms Apr 12 14:10:33 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:33 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:33 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:33 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:33 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:33 opera volumio[28816]: info: Clearing queue after UPNP request Apr 12 14:10:34 opera volumio[28816]: info: CoreStateMachine::ClearQueue Apr 12 14:10:34 opera volumio[28816]: info: CoreStateMachine::stop Apr 12 14:10:34 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:10:34 opera volumio[28816]: verbose: UNSET VOLATILE Apr 12 14:10:34 opera volumio[28816]: info: CorePlayQueue::clearPlayQueue Apr 12 14:10:34 opera volumio[28816]: info: CorePlayQueue::saveQueue Apr 12 14:10:34 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:34 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:34 opera volumio[28816]: info: CoreCommandRouter::volumioPushQueue Apr 12 14:10:34 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: error: updateQueue error: null Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 2ms Apr 12 14:10:34 opera volumio[28816]: info: Starting UPNP Playback Apr 12 14:10:34 opera volumio[28816]: info: Preparing playback through UPNP Apr 12 14:10:34 opera volumio[28816]: info: CoreCommandRouter::volumioGetState Apr 12 14:10:34 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:34 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 2ms Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 0ms Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:34 opera volumio[28816]: info: Apr 12 14:10:34 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:34 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:34 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:35 opera volumio[28816]: info: Apr 12 14:10:35 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:35 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:35 opera volumio[28816]: info: sendMpdCommand status took 2 milliseconds Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:35 opera volumio[28816]: info: Apr 12 14:10:35 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:35 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:35 opera volumio[28816]: info: Apr 12 14:10:35 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:35 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:35 opera volumio[28816]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:35 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:35 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:10:35 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"775 Kbps","isStreaming":false,"title":"亲亲","artist":"张学友","album":"只愿一生爱一人","uri":"http://aqqmusic.tc.qq.com/F000001HPU7T27BSyD.flac?guid=000000005b56b722000001937137c5ff&vkey=9EFAE982A5C8CF528BA1A61F0700DB9DCB1C36C26BE047E30AF1CB99CD1708E344553C51E7176954069FD054E8325C3F5E0D3315914A996F__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:35 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus stop Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:35 opera volumio[28816]: info: ------------------------------ 19ms Apr 12 14:10:35 opera volumio[28816]: info: ------------------------------ 16ms Apr 12 14:10:35 opera volumio[28816]: info: sendMpdCommand status took 17 milliseconds Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:35 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:35 opera volumio[28816]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 12 14:10:35 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:35 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:35 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:10:35 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"亲亲","artist":"张学友","album":"只愿一生爱一人","uri":"http://aqqmusic.tc.qq.com/F000001HPU7T27BSyD.flac?guid=000000005b56b722000001937137c5ff&vkey=9EFAE982A5C8CF528BA1A61F0700DB9DCB1C36C26BE047E30AF1CB99CD1708E344553C51E7176954069FD054E8325C3F5E0D3315914A996F__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:35 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:10:35 opera volumio[28816]: info: Received an update from plugin. extracting info from payload Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:35 opera volumio[28816]: info: ------------------------------ 41ms Apr 12 14:10:35 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:35 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:35 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:35 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:35 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:35 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:54 opera volumio[28816]: info: Apr 12 14:10:54 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:54 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:54 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:54 opera volumio[28816]: info: sendMpdCommand status took 1 milliseconds Apr 12 14:10:54 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:54 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:54 opera volumio[28816]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 14:10:54 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:54 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:54 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:54 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:54 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:54 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:54 opera volumio[28816]: 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":"亲亲","artist":"张学友","album":"只愿一生爱一人","uri":"http://aqqmusic.tc.qq.com/F000001HPU7T27BSyD.flac?guid=000000005b56b722000001937137c5ff&vkey=9EFAE982A5C8CF528BA1A61F0700DB9DCB1C36C26BE047E30AF1CB99CD1708E344553C51E7176954069FD054E8325C3F5E0D3315914A996F__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:54 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::syncState stateService stop Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::play index undefined Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:54 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:54 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:54 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:54 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:54 opera volumio[28816]: info: ------------------------------ 12ms Apr 12 14:10:54 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:54 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:54 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:54 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:54 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:54 opera volumio[28816]: info: Clearing queue after UPNP request Apr 12 14:10:55 opera volumio[28816]: info: CoreStateMachine::ClearQueue Apr 12 14:10:55 opera volumio[28816]: info: CoreStateMachine::stop Apr 12 14:10:55 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:10:55 opera volumio[28816]: verbose: UNSET VOLATILE Apr 12 14:10:55 opera volumio[28816]: info: CorePlayQueue::clearPlayQueue Apr 12 14:10:55 opera volumio[28816]: info: CorePlayQueue::saveQueue Apr 12 14:10:55 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:55 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:55 opera volumio[28816]: info: CoreCommandRouter::volumioPushQueue Apr 12 14:10:55 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: error: updateQueue error: null Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:55 opera volumio[28816]: info: Starting UPNP Playback Apr 12 14:10:55 opera volumio[28816]: info: Preparing playback through UPNP Apr 12 14:10:55 opera volumio[28816]: info: CoreCommandRouter::volumioGetState Apr 12 14:10:55 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:10:55 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 0ms Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:55 opera volumio[28816]: info: Apr 12 14:10:55 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:55 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:55 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:10:56 opera volumio[28816]: info: Apr 12 14:10:56 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:56 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:56 opera volumio[28816]: info: Apr 12 14:10:56 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:10:56 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:10:56 opera volumio[28816]: info: Apr 12 14:10:56 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:10:56 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:10:56 opera volumio[28816]: info: sendMpdCommand status took 9 milliseconds Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:56 opera volumio[28816]: info: ------------------------------ 14ms Apr 12 14:10:56 opera volumio[28816]: info: sendMpdCommand status took 10 milliseconds Apr 12 14:10:56 opera volumio[28816]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:56 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:56 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:10:56 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"草原月色","artist":"Michael Hoppé","album":"诗的沙漏","uri":"http://aqqmusic.tc.qq.com/F000003Je97D2pDj7Z.flac?guid=000000005b56b722000001937137c5ff&vkey=2F8EA968D263104F82697ABD92F845AA32E71BE6E59E98DD60F4D8BB81D1439E29B770CC6EC05F73C3BE32F09C3C5888B81C300668339976__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:56 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus stop Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:56 opera volumio[28816]: info: ------------------------------ 45ms Apr 12 14:10:56 opera volumio[28816]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 12 14:10:56 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:10:56 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:10:56 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:10:56 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"草原月色","artist":"Michael Hoppé","album":"诗的沙漏","uri":"http://aqqmusic.tc.qq.com/F000003Je97D2pDj7Z.flac?guid=000000005b56b722000001937137c5ff&vkey=2F8EA968D263104F82697ABD92F845AA32E71BE6E59E98DD60F4D8BB81D1439E29B770CC6EC05F73C3BE32F09C3C5888B81C300668339976__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:10:56 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:10:56 opera volumio[28816]: info: Received an update from plugin. extracting info from payload Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:10:56 opera volumio[28816]: info: ------------------------------ 60ms Apr 12 14:10:56 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:56 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:56 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:56 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:10:56 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:10:56 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:10:56 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:11:20 opera volumio[28816]: info: Apr 12 14:11:20 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:11:20 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:11:20 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:11:20 opera volumio[28816]: info: sendMpdCommand status took 0 milliseconds Apr 12 14:11:20 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:11:20 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:11:20 opera volumio[28816]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 14:11:20 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:11:20 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:11:20 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:11:20 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:11:20 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:11:20 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:11:20 opera volumio[28816]: 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":"草原月色","artist":"Michael Hoppé","album":"诗的沙漏","uri":"http://aqqmusic.tc.qq.com/F000003Je97D2pDj7Z.flac?guid=000000005b56b722000001937137c5ff&vkey=2F8EA968D263104F82697ABD92F845AA32E71BE6E59E98DD60F4D8BB81D1439E29B770CC6EC05F73C3BE32F09C3C5888B81C300668339976__v2b9ab33c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:11:20 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::syncState stateService stop Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::play index undefined Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:11:20 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:11:20 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:11:20 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:11:20 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:11:20 opera volumio[28816]: info: ------------------------------ 13ms Apr 12 14:11:20 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:11:20 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:11:20 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:11:20 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:11:20 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:11:21 opera volumio[28816]: info: Clearing queue after UPNP request Apr 12 14:11:21 opera volumio[28816]: info: CoreStateMachine::ClearQueue Apr 12 14:11:21 opera volumio[28816]: info: CoreStateMachine::stop Apr 12 14:11:21 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:11:21 opera volumio[28816]: verbose: UNSET VOLATILE Apr 12 14:11:21 opera volumio[28816]: info: CorePlayQueue::clearPlayQueue Apr 12 14:11:21 opera volumio[28816]: info: CorePlayQueue::saveQueue Apr 12 14:11:21 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:11:21 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:11:21 opera volumio[28816]: info: CoreCommandRouter::volumioPushQueue Apr 12 14:11:21 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:11:21 opera volumio[28816]: info: Apr 12 14:11:21 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:21 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:21 opera volumio[28816]: error: updateQueue error: null Apr 12 14:11:21 opera volumio[28816]: info: ------------------------------ 2ms Apr 12 14:11:21 opera volumio[28816]: info: Starting UPNP Playback Apr 12 14:11:21 opera volumio[28816]: info: Preparing playback through UPNP Apr 12 14:11:21 opera volumio[28816]: info: CoreCommandRouter::volumioGetState Apr 12 14:11:21 opera volumio[28816]: info: CorePlayQueue::getTrack 0 Apr 12 14:11:21 opera volumio[28816]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 2ms Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 0ms Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 1ms Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:11:22 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces state update: player Apr 12 14:11:22 opera volumio[28816]: info: Apr 12 14:11:22 opera volumio[28816]: ---------------------------- MPD announces system playlist update Apr 12 14:11:22 opera volumio[28816]: info: Ignoring MPD Status Update Apr 12 14:11:22 opera volumio[28816]: info: ControllerMpd::getState Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:11:22 opera volumio[28816]: info: sendMpdCommand status took 4 milliseconds Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:11:22 opera volumio[28816]: info: sendMpdCommand status took 2 milliseconds Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 4ms Apr 12 14:11:22 opera volumio[28816]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::parseState Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:11:22 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:11:22 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:11:22 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"577 Kbps","isStreaming":false,"title":"贝拉","artist":"Michael Hoppé","album":"诗的沙漏","uri":"http://aqqmusic.tc.qq.com/F000001xldP83swXdN.flac?guid=000000005b56b722000001937137c5ff&vkey=134D49B6A657A05C25D94D2D9FB4E0AD8BF634A19D7B1D6043C57F9C65464B1E0B8F1E59EB350264EBFD1A74B83AF1BAC95F37748BC5D5DF__v2b9ab34c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:11:22 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus stop Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 16ms Apr 12 14:11:22 opera volumio[28816]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 12 14:11:22 opera volumio[28816]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:11:22 opera volumio[28816]: info: ControllerMpd::pushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::servicePushState Apr 12 14:11:22 opera volumio[28816]: verbose: In UPNP mode Apr 12 14:11:22 opera volumio[28816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"577 Kbps","isStreaming":false,"title":"贝拉","artist":"Michael Hoppé","album":"诗的沙漏","uri":"http://aqqmusic.tc.qq.com/F000001xldP83swXdN.flac?guid=000000005b56b722000001937137c5ff&vkey=134D49B6A657A05C25D94D2D9FB4E0AD8BF634A19D7B1D6043C57F9C65464B1E0B8F1E59EB350264EBFD1A74B83AF1BAC95F37748BC5D5DF__v2b9ab34c&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Apr 12 14:11:22 opera volumio[28816]: verbose: CURRENT POSITION 0 Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::syncState stateService play Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:11:22 opera volumio[28816]: info: Received an update from plugin. extracting info from payload Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::pushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::volumioPushState Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:11:22 opera volumio[28816]: info: ------------------------------ 28ms Apr 12 14:11:22 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:11:22 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:11:22 opera volumio[28816]: info: Updating RAAT Signal Path Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:11:22 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:11:22 opera volumio[28816]: info: CoreCommandRouter::volumioGetQueue Apr 12 14:11:22 opera volumio[28816]: info: CoreStateMachine::getQueue Apr 12 14:11:22 opera volumio[28816]: info: CorePlayQueue::getQueue Apr 12 14:11:25 opera volumio[28816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 14:11:25 opera volumio[28816]: { Error: connect ETIMEDOUT 104.16.251.55:443 Apr 12 14:11:25 opera volumio[28816]: at Object._errnoException (util.js:1022:11) Apr 12 14:11:25 opera volumio[28816]: at _exceptionWithHostPort (util.js:1044:20) Apr 12 14:11:25 opera volumio[28816]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 12 14:11:25 opera volumio[28816]: code: 'ETIMEDOUT', Apr 12 14:11:25 opera volumio[28816]: errno: 'ETIMEDOUT', Apr 12 14:11:25 opera volumio[28816]: syscall: 'connect', Apr 12 14:11:25 opera volumio[28816]: address: '104.16.251.55', Apr 12 14:11:25 opera volumio[28816]: port: 443 } Apr 12 14:11:25 opera volumio[28816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 14:11:25 opera sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-12 14:10 Apr 12 14:11:25 opera sudo[1830]: 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"