-- Logs begin at Fri 2024-11-29 07:06:16 UTC, end at Mon 2024-12-02 06:19:45 UTC. -- Dec 02 06:18:11 mx volumio[4365]: info: Dec 02 06:18:11 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:11 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:11 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:11 mx volumio[4365]: info: sendMpdCommand status took 3 milliseconds Dec 02 06:18:11 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:11 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:11 mx volumio[4365]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 06:18:11 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:11 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:11 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:11 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:11 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:11 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:11 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:11 mx volumio[4365]: 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":"冰河时代2","artist":"网络歌手","album":"空","uri":"http://aqqmusic.tc.qq.com/M800001nkOVE1NnTaZ.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=96817A6E040F47CA0758CC61001CA5A654093440AEF085F3820AC8A897A62D34FD0C22E5831E041DE292356A4A696662B28930AAB42B20A2&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:12 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:12 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:12 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:12 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:12 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 29ms Dec 02 06:18:12 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:12 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:12 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:12 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:18:12 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:18:12 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:18:12 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:12 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:12 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:18:12 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: error: updateQueue error: null Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:12 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:18:12 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:18:12 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:18:12 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:12 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:18:12 mx volumio[4365]: info: Dec 02 06:18:12 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:12 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:12 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:13 mx volumio[4365]: info: Dec 02 06:18:13 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:13 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:13 mx volumio[4365]: info: Dec 02 06:18:13 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:13 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:13 mx volumio[4365]: info: Dec 02 06:18:13 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:13 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:13 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:13 mx volumio[4365]: info: sendMpdCommand status took 3 milliseconds Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:13 mx volumio[4365]: info: ------------------------------ 4ms Dec 02 06:18:13 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:18:13 mx volumio[4365]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:13 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:13 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:13 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":317,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冰河时代2","artist":"网络歌手","album":"空","uri":"http://aqqmusic.tc.qq.com/M800001nkOVE1NnTaZ.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=96817A6E040F47CA0758CC61001CA5A654093440AEF085F3820AC8A897A62D34FD0C22E5831E041DE292356A4A696662B28930AAB42B20A2&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:13 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:13 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:13 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:13 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":317,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"冰河时代2","artist":"网络歌手","album":"空","uri":"http://aqqmusic.tc.qq.com/M800001nkOVE1NnTaZ.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=96817A6E040F47CA0758CC61001CA5A654093440AEF085F3820AC8A897A62D34FD0C22E5831E041DE292356A4A696662B28930AAB42B20A2&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:13 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:13 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:13 mx volumio[4365]: info: ------------------------------ 54ms Dec 02 06:18:13 mx volumio[4365]: info: ------------------------------ 52ms Dec 02 06:18:13 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:13 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:13 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:13 mx volumio[4365]: info: Dec 02 06:18:13 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:13 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:13 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:13 mx volumio[4365]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 06:18:13 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:13 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:13 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:13 mx volumio[4365]: 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":"冰河时代2","artist":"网络歌手","album":"空","uri":"http://aqqmusic.tc.qq.com/M800001nkOVE1NnTaZ.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=96817A6E040F47CA0758CC61001CA5A654093440AEF085F3820AC8A897A62D34FD0C22E5831E041DE292356A4A696662B28930AAB42B20A2&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:13 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:13 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:13 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:13 mx volumio[4365]: info: ------------------------------ 18ms Dec 02 06:18:13 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:13 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:13 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:18:13 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:13 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:18:13 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:18:13 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:13 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:18:13 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:13 mx volumio[4365]: info: Dec 02 06:18:13 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:13 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:13 mx volumio[4365]: error: updateQueue error: null Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:14 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:18:14 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:18:14 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 9ms Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:14 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:14 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:14 mx volumio[4365]: info: Dec 02 06:18:14 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:14 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:14 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 4ms Dec 02 06:18:14 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:14 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:14 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:14 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:14 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":181,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"La La Love On My Mind","artist":"Ann Winsborn","album":"La La Love On My Mind","uri":"http://aqqmusic.tc.qq.com/M8000016LAKQ1TFxHa.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=F3044054AC612913151F94929EAF45549FB4F66F0508FED9853C788ADA93F66A8E5459182B5CF8A8DBFDB342C74EBEEE06030F60C25145F8&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:14 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 27ms Dec 02 06:18:14 mx volumio[4365]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 02 06:18:14 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:14 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:14 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:14 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":181,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"La La Love On My Mind","artist":"Ann Winsborn","album":"La La Love On My Mind","uri":"http://aqqmusic.tc.qq.com/M8000016LAKQ1TFxHa.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=F3044054AC612913151F94929EAF45549FB4F66F0508FED9853C788ADA93F66A8E5459182B5CF8A8DBFDB342C74EBEEE06030F60C25145F8&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:14 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:14 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:14 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:14 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:14 mx volumio[4365]: info: ------------------------------ 50ms Dec 02 06:18:14 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:14 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:14 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:14 mx volumio[4365]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ann%20Winsborn/La%20La%20Love%20On%20My%20Mind/a75f6845-04ea-40f6-9582-d2353b3db943.jpg' Dec 02 06:18:15 mx volumio[4365]: info: Dec 02 06:18:15 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:15 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:15 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:15 mx volumio[4365]: info: sendMpdCommand status took 1 milliseconds Dec 02 06:18:15 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:15 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:15 mx volumio[4365]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 06:18:15 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:15 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:15 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:15 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:15 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:15 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:15 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:15 mx volumio[4365]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La La Love On My Mind","artist":"Ann Winsborn","album":"La La Love On My Mind","uri":"http://aqqmusic.tc.qq.com/M8000016LAKQ1TFxHa.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=F3044054AC612913151F94929EAF45549FB4F66F0508FED9853C788ADA93F66A8E5459182B5CF8A8DBFDB342C74EBEEE06030F60C25145F8&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:15 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:15 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:18:15 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:15 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:18:15 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:15 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:15 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:15 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:15 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:15 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:15 mx volumio[4365]: info: ------------------------------ 19ms Dec 02 06:18:15 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:15 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:15 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:16 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:18:16 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:18:16 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:18:16 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: error: updateQueue error: null Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:18:16 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:18:16 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:16 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:16 mx volumio[4365]: info: sendMpdCommand status took 3 milliseconds Dec 02 06:18:16 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:16 mx volumio[4365]: info: Dec 02 06:18:16 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:16 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:16 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:16 mx volumio[4365]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:16 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:16 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:16 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"玩腻","artist":"群星","album":"空","uri":"http://aqqmusic.tc.qq.com/M500000pLMEE3hF9NG.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=479C5CB2AE81574EDAA5F1321A4589A38DD13D6E47206646A5C21D2B975EDBA52B517E9CB272B12D62CE1FDDDC94B2B9AC9A092BD18C1A1F&uin=2687950226&redirect=1&fromtag=111042","trackType":""} Dec 02 06:18:16 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:18:16 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 20ms Dec 02 06:18:16 mx volumio[4365]: info: ------------------------------ 16ms Dec 02 06:18:16 mx volumio[4365]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 02 06:18:16 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:16 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:16 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:16 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:16 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"玩腻","artist":"群星","album":"空","uri":"http://aqqmusic.tc.qq.com/M500000pLMEE3hF9NG.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=479C5CB2AE81574EDAA5F1321A4589A38DD13D6E47206646A5C21D2B975EDBA52B517E9CB272B12D62CE1FDDDC94B2B9AC9A092BD18C1A1F&uin=2687950226&redirect=1&fromtag=111042","trackType":""} Dec 02 06:18:17 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:17 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:17 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:17 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:17 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:17 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:17 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:17 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:17 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:17 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:17 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:17 mx volumio[4365]: info: ------------------------------ 42ms Dec 02 06:18:17 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:17 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:17 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:19 mx volumio[4365]: info: Dec 02 06:18:19 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:19 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:19 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:19 mx volumio[4365]: info: sendMpdCommand status took 1 milliseconds Dec 02 06:18:19 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:19 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:19 mx volumio[4365]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 06:18:19 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:19 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:19 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:19 mx volumio[4365]: 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/M500000pLMEE3hF9NG.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=479C5CB2AE81574EDAA5F1321A4589A38DD13D6E47206646A5C21D2B975EDBA52B517E9CB272B12D62CE1FDDDC94B2B9AC9A092BD18C1A1F&uin=2687950226&redirect=1&fromtag=111042","trackType":""} Dec 02 06:18:19 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:19 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:19 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:19 mx volumio[4365]: info: ------------------------------ 24ms Dec 02 06:18:19 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:19 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:19 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:19 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:18:19 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:18:19 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:18:19 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:19 mx volumio[4365]: info: Dec 02 06:18:19 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:19 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:19 mx volumio[4365]: error: updateQueue error: null Dec 02 06:18:19 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:19 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:18:19 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:18:19 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:18:19 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:19 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:20 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:20 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:20 mx volumio[4365]: info: sendMpdCommand status took 6 milliseconds Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:20 mx volumio[4365]: info: Dec 02 06:18:20 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:20 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:20 mx volumio[4365]: info: sendMpdCommand status took 3 milliseconds Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:20 mx volumio[4365]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 7ms Dec 02 06:18:20 mx volumio[4365]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:20 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:20 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:20 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:20 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":70,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"渔舟唱晚","artist":"群星","album":"史上最优美的背景音乐Vol.1","uri":"http://aqqmusic.tc.qq.com/M800001X90N206bfrz.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=A84DAE2159C3D2FA62A5F92959DAA92777168F04624AC9F2FBAED7E12665A39B20318622338286D09FDF676F3EBBCFCDF08DC9565299EAE9&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:20 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:20 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:20 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:20 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":70,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"渔舟唱晚","artist":"群星","album":"史上最优美的背景音乐Vol.1","uri":"http://aqqmusic.tc.qq.com/M800001X90N206bfrz.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=A84DAE2159C3D2FA62A5F92959DAA92777168F04624AC9F2FBAED7E12665A39B20318622338286D09FDF676F3EBBCFCDF08DC9565299EAE9&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:20 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:20 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:20 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:20 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 41ms Dec 02 06:18:20 mx volumio[4365]: info: ------------------------------ 36ms Dec 02 06:18:20 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:20 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:20 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:22 mx volumio[4365]: info: Dec 02 06:18:22 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:22 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:22 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:22 mx volumio[4365]: info: sendMpdCommand status took 5 milliseconds Dec 02 06:18:22 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:22 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:22 mx volumio[4365]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 06:18:22 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:22 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:22 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:22 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:22 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16000,"duration":70,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"渔舟唱晚","artist":"群星","album":"史上最优美的背景音乐Vol.1","uri":"http://aqqmusic.tc.qq.com/M800001X90N206bfrz.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=A84DAE2159C3D2FA62A5F92959DAA92777168F04624AC9F2FBAED7E12665A39B20318622338286D09FDF676F3EBBCFCDF08DC9565299EAE9&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:22 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:22 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:22 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:22 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:22 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:22 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:22 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:22 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:22 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:22 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:22 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:22 mx volumio[4365]: info: ------------------------------ 29ms Dec 02 06:18:22 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:22 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:29 mx volumio[4365]: info: Dec 02 06:18:29 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:29 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:29 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:29 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:29 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:29 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:29 mx volumio[4365]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 02 06:18:29 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:29 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:29 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:29 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:29 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":70,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"渔舟唱晚","artist":"群星","album":"史上最优美的背景音乐Vol.1","uri":"http://aqqmusic.tc.qq.com/M800001X90N206bfrz.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=A84DAE2159C3D2FA62A5F92959DAA92777168F04624AC9F2FBAED7E12665A39B20318622338286D09FDF676F3EBBCFCDF08DC9565299EAE9&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:29 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:29 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:29 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:29 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:29 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:29 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:29 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:29 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:29 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:29 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:29 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:29 mx volumio[4365]: info: ------------------------------ 25ms Dec 02 06:18:29 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:29 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:33 mx volumio[4365]: info: Dec 02 06:18:33 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:33 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:33 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:33 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:18:33 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:33 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:33 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:18:33 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:33 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:33 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:33 mx volumio[4365]: 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":"史上最优美的背景音乐Vol.1","uri":"http://aqqmusic.tc.qq.com/M800001X90N206bfrz.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=A84DAE2159C3D2FA62A5F92959DAA92777168F04624AC9F2FBAED7E12665A39B20318622338286D09FDF676F3EBBCFCDF08DC9565299EAE9&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:33 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:33 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:33 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:33 mx volumio[4365]: info: ------------------------------ 25ms Dec 02 06:18:33 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:33 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:33 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:18:33 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:18:33 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:18:33 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:18:33 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:33 mx volumio[4365]: info: Dec 02 06:18:33 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:33 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:33 mx volumio[4365]: error: updateQueue error: null Dec 02 06:18:33 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:33 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:18:33 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:18:33 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:18:33 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:18:33 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 1ms Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 1ms Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 1ms Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:34 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:34 mx volumio[4365]: info: sendMpdCommand status took 6 milliseconds Dec 02 06:18:34 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:34 mx volumio[4365]: info: Dec 02 06:18:34 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:18:34 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:18:34 mx volumio[4365]: info: sendMpdCommand status took 3 milliseconds Dec 02 06:18:34 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:34 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:34 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:34 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":297,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"倾国倾城","artist":"阿宝","album":"倾国倾城","uri":"http://aqqmusic.tc.qq.com/M8000011suw43fpTd1.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1640F5A2823D85FC2BC05ED701E6D91F0A6F9351FB0C8EFE4ACD9B307CC8BBCD89B3B30A245524201F64861A0CC4872C29CC103D7D15B970&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:34 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 21ms Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 13ms Dec 02 06:18:34 mx volumio[4365]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 02 06:18:34 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:34 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:34 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:34 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":297,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"倾国倾城","artist":"阿宝","album":"倾国倾城","uri":"http://aqqmusic.tc.qq.com/M8000011suw43fpTd1.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1640F5A2823D85FC2BC05ED701E6D91F0A6F9351FB0C8EFE4ACD9B307CC8BBCD89B3B30A245524201F64861A0CC4872C29CC103D7D15B970&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:34 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:34 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:34 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:34 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:34 mx volumio[4365]: info: ------------------------------ 39ms Dec 02 06:18:34 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:34 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:34 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:40 mx volumio[4365]: info: Dec 02 06:18:40 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:18:40 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:18:40 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:18:40 mx volumio[4365]: info: sendMpdCommand status took 1 milliseconds Dec 02 06:18:40 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:18:40 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:18:40 mx volumio[4365]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 06:18:40 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:18:40 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:18:40 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:18:40 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:18:40 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38000,"duration":297,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"倾国倾城","artist":"阿宝","album":"倾国倾城","uri":"http://aqqmusic.tc.qq.com/M8000011suw43fpTd1.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1640F5A2823D85FC2BC05ED701E6D91F0A6F9351FB0C8EFE4ACD9B307CC8BBCD89B3B30A245524201F64861A0CC4872C29CC103D7D15B970&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:18:40 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:18:40 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:18:40 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:18:40 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:18:40 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:18:40 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:40 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:40 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:40 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:18:40 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:18:40 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:18:40 mx volumio[4365]: info: ------------------------------ 17ms Dec 02 06:18:40 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:18:40 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:02 mx volumio[4365]: info: Dec 02 06:19:02 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:02 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:02 mx volumio[4365]: info: sendMpdCommand status took 1 milliseconds Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:02 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:02 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:02 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:19:02 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98000,"duration":297,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"倾国倾城","artist":"阿宝","album":"倾国倾城","uri":"http://aqqmusic.tc.qq.com/M8000011suw43fpTd1.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1640F5A2823D85FC2BC05ED701E6D91F0A6F9351FB0C8EFE4ACD9B307CC8BBCD89B3B30A245524201F64861A0CC4872C29CC103D7D15B970&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:19:02 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:19:02 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:02 mx volumio[4365]: info: ------------------------------ 30ms Dec 02 06:19:02 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:02 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:02 mx volumio[4365]: info: Dec 02 06:19:02 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:02 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:02 mx volumio[4365]: info: sendMpdCommand status took 1 milliseconds Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:02 mx volumio[4365]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 02 06:19:02 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:02 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:02 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:19:02 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98000,"duration":297,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"倾国倾城","artist":"阿宝","album":"倾国倾城","uri":"http://aqqmusic.tc.qq.com/M8000011suw43fpTd1.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1640F5A2823D85FC2BC05ED701E6D91F0A6F9351FB0C8EFE4ACD9B307CC8BBCD89B3B30A245524201F64861A0CC4872C29CC103D7D15B970&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:19:02 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:19:02 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:02 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:02 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:02 mx volumio[4365]: info: ------------------------------ 22ms Dec 02 06:19:02 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:02 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:08 mx volumio[4365]: info: Dec 02 06:19:08 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:08 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:08 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:08 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:19:08 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:08 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:08 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:19:08 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:08 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:08 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:08 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:08 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:08 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:08 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:08 mx volumio[4365]: 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/M8000011suw43fpTd1.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=1640F5A2823D85FC2BC05ED701E6D91F0A6F9351FB0C8EFE4ACD9B307CC8BBCD89B3B30A245524201F64861A0CC4872C29CC103D7D15B970&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:19:08 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:08 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:19:08 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:19:08 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:19:08 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:19:08 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:08 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:08 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:08 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:08 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:08 mx volumio[4365]: info: ------------------------------ 30ms Dec 02 06:19:08 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:08 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:08 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:19:09 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:19:09 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:19:09 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:19:09 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: error: updateQueue error: null Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 5ms Dec 02 06:19:09 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:19:09 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:19:09 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 3ms Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:09 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:09 mx volumio[4365]: info: sendMpdCommand status took 5 milliseconds Dec 02 06:19:09 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:09 mx volumio[4365]: info: Dec 02 06:19:09 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:09 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:09 mx volumio[4365]: info: sendMpdCommand status took 2 milliseconds Dec 02 06:19:09 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:09 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:09 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:19:09 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359,"duration":383,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"朋友的酒(ＤJ小鱼儿版)","artist":"李晓杰","album":"独一舞二","uri":"http://isure6.stream.qqmusic.qq.com/M800003jXPdw2qyOgU.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=79E2CAD830CAF1FCD28E373BFADD37016F37E610FB8C1C2B1DD68EBABE27CEBB63C10740465AC99A43A8213D10E719CD3D3BEA3CEE609C07&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:19:09 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 20ms Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 14ms Dec 02 06:19:09 mx volumio[4365]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 02 06:19:09 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:09 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:09 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:19:09 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":383,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"朋友的酒(ＤJ小鱼儿版)","artist":"李晓杰","album":"独一舞二","uri":"http://isure6.stream.qqmusic.qq.com/M800003jXPdw2qyOgU.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=79E2CAD830CAF1FCD28E373BFADD37016F37E610FB8C1C2B1DD68EBABE27CEBB63C10740465AC99A43A8213D10E719CD3D3BEA3CEE609C07&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:19:09 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:19:09 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:09 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:09 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:09 mx volumio[4365]: info: ------------------------------ 37ms Dec 02 06:19:09 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:09 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:09 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:22 mx volumio[4365]: info: Dec 02 06:19:22 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:22 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:22 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:22 mx volumio[4365]: info: sendMpdCommand status took 1 milliseconds Dec 02 06:19:22 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:22 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:22 mx volumio[4365]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 06:19:22 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:22 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:22 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:22 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:22 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:22 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:22 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:22 mx volumio[4365]: 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":"朋友的酒(ＤJ小鱼儿版)","artist":"李晓杰","album":"独一舞二","uri":"http://isure6.stream.qqmusic.qq.com/M800003jXPdw2qyOgU.mp3?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=79E2CAD830CAF1FCD28E373BFADD37016F37E610FB8C1C2B1DD68EBABE27CEBB63C10740465AC99A43A8213D10E719CD3D3BEA3CEE609C07&uin=2687950226&redirect=1&fromtag=111093","trackType":""} Dec 02 06:19:22 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:22 mx volumio[4365]: info: CoreStateMachine::syncState stateService stop Dec 02 06:19:22 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:19:22 mx volumio[4365]: info: CoreStateMachine::play index undefined Dec 02 06:19:22 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:19:22 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:22 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:22 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:22 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:22 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:22 mx volumio[4365]: info: ------------------------------ 18ms Dec 02 06:19:22 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:22 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:22 mx volumio[4365]: info: Clearing queue after UPNP request Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::ClearQueue Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::stop Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 06:19:23 mx volumio[4365]: verbose: UNSET VOLATILE Dec 02 06:19:23 mx volumio[4365]: info: CorePlayQueue::clearPlayQueue Dec 02 06:19:23 mx volumio[4365]: info: CorePlayQueue::saveQueue Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::volumioPushQueue Dec 02 06:19:23 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: error: updateQueue error: null Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: Starting UPNP Playback Dec 02 06:19:23 mx volumio[4365]: info: Preparing playback through UPNP Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::volumioGetState Dec 02 06:19:23 mx volumio[4365]: info: CorePlayQueue::getTrack 0 Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 2ms Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:23 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces state update: player Dec 02 06:19:23 mx volumio[4365]: info: sendMpdCommand status took 4 milliseconds Dec 02 06:19:23 mx volumio[4365]: info: ControllerMpd::getState Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand status Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:23 mx volumio[4365]: info: Dec 02 06:19:23 mx volumio[4365]: ---------------------------- MPD announces system playlist update Dec 02 06:19:23 mx volumio[4365]: info: Ignoring MPD Status Update Dec 02 06:19:23 mx volumio[4365]: info: sendMpdCommand status took 3 milliseconds Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::parseState Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 06:19:23 mx volumio[4365]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 6ms Dec 02 06:19:23 mx volumio[4365]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:23 mx volumio[4365]: verbose: ControllerMpd::parseTrackInfo Dec 02 06:19:23 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:23 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:19:23 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":232,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"朋友的酒(ＤJ小鱼儿版)","artist":"李晓杰","album":"独一舞二","uri":"http://isure6.stream.qqmusic.qq.com/F000003jXPdw2qyOgU.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=CD84E97FC61E9B0EE6A4CED33D5354443740B8F273C3B8F34F898B296014652E9951FF92267506DE78A94454974A2995FA9C4CF46B8BD06F&uin=2687950226&redirect=1&fromtag=111114","trackType":""} Dec 02 06:19:23 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus stop Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:23 mx volumio[4365]: info: ControllerMpd::pushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::servicePushState Dec 02 06:19:23 mx volumio[4365]: verbose: In UPNP mode Dec 02 06:19:23 mx volumio[4365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"883 Kbps","isStreaming":false,"title":"朋友的酒(ＤJ小鱼儿版)","artist":"李晓杰","album":"独一舞二","uri":"http://isure6.stream.qqmusic.qq.com/F000003jXPdw2qyOgU.flac?guid=ffffffffd79c5f1b00000191197bc5f7&vkey=CD84E97FC61E9B0EE6A4CED33D5354443740B8F273C3B8F34F898B296014652E9951FF92267506DE78A94454974A2995FA9C4CF46B8BD06F&uin=2687950226&redirect=1&fromtag=111114","trackType":""} Dec 02 06:19:23 mx volumio[4365]: verbose: CURRENT POSITION 0 Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::syncState stateService play Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::syncState currentStatus play Dec 02 06:19:23 mx volumio[4365]: info: Received an update from plugin. extracting info from payload Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:23 mx volumio[4365]: info: CoreStateMachine::pushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::volumioPushState Dec 02 06:19:23 mx volumio[4365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 106ms Dec 02 06:19:23 mx volumio[4365]: info: ------------------------------ 108ms Dec 02 06:19:23 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:23 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:23 mx volumio[4365]: info: Updating RAAT Signal Path Dec 02 06:19:44 mx volumio[4365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 06:19:44 mx volumio[4365]: { Error: connect ETIMEDOUT 210.56.51.192:443 Dec 02 06:19:44 mx volumio[4365]: at Object._errnoException (util.js:1022:11) Dec 02 06:19:44 mx volumio[4365]: at _exceptionWithHostPort (util.js:1044:20) Dec 02 06:19:44 mx volumio[4365]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 02 06:19:44 mx volumio[4365]: code: 'ETIMEDOUT', Dec 02 06:19:44 mx volumio[4365]: errno: 'ETIMEDOUT', Dec 02 06:19:44 mx volumio[4365]: syscall: 'connect', Dec 02 06:19:44 mx volumio[4365]: address: '210.56.51.192', Dec 02 06:19:44 mx volumio[4365]: port: 443 } Dec 02 06:19:44 mx volumio[4365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 06:19:45 mx sudo[6672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 06:18 Dec 02 06:19:45 mx sudo[6672]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"