-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-09-02 11:42:54 CST. -- Sep 02 11:41:02 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 02 11:41:02 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand update Sep 02 11:41:02 localhost volumio[4802]: info: sendMpdCommand update took 2 milliseconds Sep 02 11:41:03 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 11:41:03 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 11:41:03 localhost volumio[4802]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 02 11:41:03 localhost volumio[4802]: info: CoreCommandRouter::volumioGetState Sep 02 11:41:03 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:41:05 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 11:41:05 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:41:05 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 02 11:41:07 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::volumioGetBrowseSources Sep 02 11:41:08 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 11:41:09 localhost volumio[4802]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 02 11:41:09 localhost volumio[4802]: info: Completed starting MyVolumio Plugin Sep 02 11:41:09 localhost volumio[4802]: [Metrics] CommandRouter: 40s 327.09ms Sep 02 11:41:09 localhost volumio[4802]: info: CoreCommandRouter::volumiosetStartupVolume Sep 02 11:41:09 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:41:09 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:41:09 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:41:09 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:41:10 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 02 11:41:10 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 11:41:10 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 02 11:41:11 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 11:41:11 localhost volumio[4802]: info: CURURI: music-library/NAS/NAS音乐 Sep 02 11:41:11 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:41:16 localhost volumio[4802]: info: BOOT COMPLETED Sep 02 11:41:34 localhost sshd[5795]: Accepted password for volumio from 192.168.2.108 port 61003 ssh2 Sep 02 11:41:34 localhost sshd[5795]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 02 11:41:34 localhost systemd-logind[564]: New session 3 of user volumio. Sep 02 11:41:34 localhost systemd[1]: Started Session 3 of user volumio. Sep 02 11:41:36 localhost ntpd[776]: 193.182.111.141 local addr 192.168.2.104 -> Sep 02 11:41:38 localhost ntpd[776]: 119.28.206.193 local addr 192.168.2.104 -> Sep 02 11:41:50 localhost volumio-remote-updater[549]: No test mode Sep 02 11:41:50 localhost volumio-remote-updater[549]: No alpha test mode Sep 02 11:41:50 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 02 11:41:51 localhost volumio[4802]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 02 11:41:51 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 02 11:41:55 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:41:55 localhost volumio[4802]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:41:55 localhost volumio[4802]: info: CoreStateMachine::ClearQueue Sep 02 11:41:55 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:41:55 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:55 localhost volumio[4802]: info: CorePlayQueue::clearPlayQueue Sep 02 11:41:55 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:41:55 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:41:55 localhost volumio[4802]: info: CoreStateMachine::addQueueItems Sep 02 11:41:55 localhost volumio[4802]: info: CorePlayQueue::addQueueItems Sep 02 11:41:55 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:41:55 localhost volumio[4802]: info: Adding Item to queue: music-library/NAS/NAS音乐/有何不可 - 许嵩 Sep 02 11:41:55 localhost volumio[4802]: info: Exploding uri music-library/NAS/NAS音乐/有何不可 - 许嵩 in service mpd Sep 02 11:41:59 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:41:59 localhost volumio[4802]: info: CoreCommandRouter::volumioPlay Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::play index 0 Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::play index undefined Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:41:59 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:41:59 localhost volumio[4802]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::ClearQueue Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::clearPlayQueue Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:41:59 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::addQueueItems Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::addQueueItems Sep 02 11:41:59 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:41:59 localhost volumio[4802]: info: Adding Item to queue: music-library/NAS/NAS音乐/有何不可 - 许嵩 Sep 02 11:41:59 localhost volumio[4802]: info: Using cached record of: music-library/NAS/NAS音乐/有何不可 - 许嵩 Sep 02 11:41:59 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:41:59 localhost volumio[4802]: info: CoreCommandRouter::volumioPlay Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::play index 0 Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::play index undefined Sep 02 11:41:59 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:41:59 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:00 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:00 localhost volumio[4802]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:42:00 localhost volumio[4802]: info: CoreStateMachine::ClearQueue Sep 02 11:42:00 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:42:00 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:00 localhost volumio[4802]: info: CorePlayQueue::clearPlayQueue Sep 02 11:42:00 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:42:00 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:42:00 localhost volumio[4802]: info: CoreStateMachine::addQueueItems Sep 02 11:42:00 localhost volumio[4802]: info: CorePlayQueue::addQueueItems Sep 02 11:42:00 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:00 localhost volumio[4802]: info: Adding Item to queue: music-library/NAS/NAS音乐/黄昏 - 周传雄 Sep 02 11:42:00 localhost volumio[4802]: info: Exploding uri music-library/NAS/NAS音乐/黄昏 - 周传雄 in service mpd Sep 02 11:42:03 localhost volumio[4802]: info: ALBUMART /albumart?cacheid=665&web=%E5%91%A8%E4%BC%A0%E9%9B%84/transfer/extralarge&path=%2Fmnt%2FNAS%2FNAS%E9%9F%B3%E4%B9%90%2F%E9%BB%84%E6%98%8F%20-%20%E5%91%A8%E4%BC%A0%E9%9B%84%2F%E9%BB%84%E6%98%8F%20-%20%E5%91%A8%E4%BC%A0%E9%9B%84.mp3&metadata=false Sep 02 11:42:03 localhost volumio[4802]: info: URI /mnt/NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3 Sep 02 11:42:03 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:42:03 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:42:03 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:42:03 localhost volumio[4802]: info: CoreCommandRouter::volumioPlay Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::play index 0 Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::play index undefined Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:03 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:03 localhost volumio[4802]: info: CoreStateMachine::startPlaybackTimer Sep 02 11:42:03 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:03 localhost volumio[4802]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3 Sep 02 11:42:03 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:42:03 localhost volumio[4802]: info: sendMpdCommand stop took 4 milliseconds Sep 02 11:42:03 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 11:42:03 localhost volumio[4802]: info: Sep 02 11:42:03 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:03 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:03 localhost volumio[4802]: info: sendMpdCommand clear took 8 milliseconds Sep 02 11:42:03 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3" Sep 02 11:42:03 localhost volumio[4802]: info: Sep 02 11:42:03 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:03 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:03 localhost volumio[4802]: info: Sep 02 11:42:03 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:03 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:03 localhost volumio[4802]: error: updateQueue error: null Sep 02 11:42:03 localhost volumio[4802]: info: Sep 02 11:42:03 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:03 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:03 localhost volumio[4802]: info: ------------------------------ 26ms Sep 02 11:42:03 localhost volumio[4802]: info: sendMpdCommand add "NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3" took 13 milliseconds Sep 02 11:42:03 localhost volumio[4802]: info: ------------------------------ 13ms Sep 02 11:42:03 localhost volumio[4802]: info: ------------------------------ 10ms Sep 02 11:42:03 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand play Sep 02 11:42:03 localhost volumio[4802]: info: Sep 02 11:42:03 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:03 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:03 localhost volumio[4802]: info: Sep 02 11:42:03 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:03 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:03 localhost volumio[4802]: info: ------------------------------ 16ms Sep 02 11:42:03 localhost volumio[4802]: info: sendMpdCommand play took 9 milliseconds Sep 02 11:42:03 localhost volumio[4802]: info: ------------------------------ 8ms Sep 02 11:42:03 localhost volumio[4802]: info: ------------------------------ 6ms Sep 02 11:42:05 localhost volumio[4802]: info: Sep 02 11:42:05 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:05 localhost volumio[4802]: info: Sep 02 11:42:05 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:05 localhost volumio[4802]: info: Sep 02 11:42:05 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:05 localhost volumio[4802]: info: Sep 02 11:42:05 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand status took 9 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand status took 8 milliseconds Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:05 localhost volumio[4802]: info: Sep 02 11:42:05 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:05 localhost volumio[4802]: info: Sep 02 11:42:05 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand status took 19 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand status took 15 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand status took 10 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand status took 8 milliseconds Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:05 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:05 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:05 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: ------------------------------ 58ms Sep 02 11:42:05 localhost volumio[4802]: info: ------------------------------ 66ms Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 39 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 39 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 02 11:42:05 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:05 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:05 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:05 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:05 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:05 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:05 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:05 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:05 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:05 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:05 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:05 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:05 localhost volumio[4802]: info: ------------------------------ 159ms Sep 02 11:42:05 localhost volumio[4802]: info: ------------------------------ 157ms Sep 02 11:42:05 localhost volumio[4802]: info: ------------------------------ 150ms Sep 02 11:42:05 localhost volumio[4802]: info: ------------------------------ 149ms Sep 02 11:42:11 localhost volumio[4802]: info: MPD Database updated - AlbumList cache refreshed Sep 02 11:42:11 localhost volumio[4802]: info: Sep 02 11:42:11 localhost volumio[4802]: ---------------------------- MPD announces state update: database Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: Sep 02 11:42:11 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: MPD Database updated - AlbumList cache refreshed Sep 02 11:42:11 localhost volumio[4802]: info: Sep 02 11:42:11 localhost volumio[4802]: ---------------------------- MPD announces state update: database Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: Sep 02 11:42:11 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: MPD Database updated - AlbumList cache refreshed Sep 02 11:42:11 localhost volumio[4802]: info: Sep 02 11:42:11 localhost volumio[4802]: ---------------------------- MPD announces state update: database Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: Sep 02 11:42:11 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 57 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 56 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 55 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 53 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 52 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 52 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 49 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 49 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand status took 47 milliseconds Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 73 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 72 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 48 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 48 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 02 11:42:11 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:11 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6731,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:11 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:11 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6731,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:11 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:11 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6731,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:11 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:11 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6731,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:11 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:11 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6731,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:11 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:11 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6731,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:11 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:11 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:11 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:11 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:11 localhost volumio[4802]: info: ------------------------------ 319ms Sep 02 11:42:11 localhost volumio[4802]: info: ------------------------------ 319ms Sep 02 11:42:11 localhost volumio[4802]: info: ------------------------------ 316ms Sep 02 11:42:11 localhost volumio[4802]: info: ------------------------------ 316ms Sep 02 11:42:11 localhost volumio[4802]: info: ------------------------------ 314ms Sep 02 11:42:11 localhost volumio[4802]: info: ------------------------------ 314ms Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:16 localhost volumio[4802]: info: Sep 02 11:42:16 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:16 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:16 localhost volumio[4802]: info: Sep 02 11:42:16 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:16 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:16 localhost volumio[4802]: info: Sep 02 11:42:16 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:16 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand status took 9 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand status took 8 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand status took 7 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand status took 5 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:16 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:16 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:16 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 63 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 39 milliseconds Sep 02 11:42:16 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:16 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:16 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11977,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:16 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:16 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:16 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11977,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:16 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:16 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:16 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11977,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:16 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:16 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:16 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:16 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:16 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:16 localhost volumio[4802]: info: ------------------------------ 171ms Sep 02 11:42:16 localhost volumio[4802]: info: ------------------------------ 170ms Sep 02 11:42:16 localhost volumio[4802]: info: ------------------------------ 171ms Sep 02 11:42:17 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 11:42:17 localhost volumio[4802]: info: CURURI: music-library/NAS/NAS音乐 Sep 02 11:42:17 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:18 localhost ntpd[776]: 193.182.111.142 local addr 192.168.2.104 -> Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand update Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: Sep 02 11:42:21 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand update took 5 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: Sep 02 11:42:21 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: Sep 02 11:42:21 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 16 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 13 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 12 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 9 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 9 milliseconds Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: Sep 02 11:42:21 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 125 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 89 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 64 milliseconds Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17234,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:21 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:21 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17234,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:21 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:21 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:21 localhost volumio[4802]: info: ------------------------------ 188ms Sep 02 11:42:21 localhost volumio[4802]: info: ------------------------------ 185ms Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: Sep 02 11:42:21 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: Sep 02 11:42:21 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 88 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 85 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 85 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 16 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 12 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 11 milliseconds Sep 02 11:42:21 localhost volumio[4802]: info: sendMpdCommand status took 7 milliseconds Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:21 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:21 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17234,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:21 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:21 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:21 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:21 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:21 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: ------------------------------ 356ms Sep 02 11:42:22 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 108 milliseconds Sep 02 11:42:22 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 79 milliseconds Sep 02 11:42:22 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 47 milliseconds Sep 02 11:42:22 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:22 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:22 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:22 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17359,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:22 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:22 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17485,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:22 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:22 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17485,"duration":344,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄昏","artist":"周传雄","album":"transfer","uri":"NAS/NAS音乐/黄昏 - 周传雄/黄昏 - 周传雄.mp3","trackType":"mp3"} Sep 02 11:42:22 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:22 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:22 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:22 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:22 localhost volumio[4802]: info: ------------------------------ 304ms Sep 02 11:42:22 localhost volumio[4802]: info: ------------------------------ 230ms Sep 02 11:42:22 localhost volumio[4802]: info: ------------------------------ 225ms Sep 02 11:42:23 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 11:42:23 localhost volumio[4802]: info: CURURI: music-library/NAS/NAS音乐/Booty Music - Deep Side Sep 02 11:42:23 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:23 localhost volumio[4802]: info: Preloading song: music-library/NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3 Sep 02 11:42:23 localhost volumio[4802]: info: Exploding uri music-library/NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3 in service mpd Sep 02 11:42:23 localhost volumio[4802]: info: ALBUMART /albumart?cacheid=665&web=Deep%20Side/Git%20Fresh/extralarge&path=%2Fmnt%2FNAS%2FNAS%E9%9F%B3%E4%B9%90%2FBooty%20Music%20-%20Deep%20Side%2FBooty%20Music%20-%20Deep%20Side.mp3&metadata=false Sep 02 11:42:23 localhost volumio[4802]: info: URI /mnt/NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3 Sep 02 11:42:24 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::ClearQueue Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::stPlaybackTimer Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::serviceStop Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::serviceStop Sep 02 11:42:24 localhost volumio[4802]: info: ControllerMpd::stop Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::clearPlayQueue Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::addQueueItems Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::addQueueItems Sep 02 11:42:24 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:24 localhost volumio[4802]: info: Adding Item to queue: music-library/NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3 Sep 02 11:42:24 localhost volumio[4802]: info: Using cached record of: music-library/NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPlay Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::play index 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::play index undefined Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::startPlaybackTimer Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3 Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand stop took 31 milliseconds Sep 02 11:42:24 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand stop took 9 milliseconds Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:24 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:24 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:24 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand status took 8 milliseconds Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand clear took 6 milliseconds Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3" Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:24 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 11:42:24 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::syncState stateService stop Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: No code Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 11:42:24 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::syncState stateService stop Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: No code Sep 02 11:42:24 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:24 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:24 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:24 localhost volumio[4802]: info: ------------------------------ 67ms Sep 02 11:42:24 localhost volumio[4802]: info: ------------------------------ 66ms Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:24 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:24 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:24 localhost volumio[4802]: error: updateQueue error: null Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:24 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:24 localhost volumio[4802]: info: ------------------------------ 71ms Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 68 milliseconds Sep 02 11:42:24 localhost volumio[4802]: info: sendMpdCommand add "NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3" took 68 milliseconds Sep 02 11:42:24 localhost volumio[4802]: info: ------------------------------ 7ms Sep 02 11:42:24 localhost volumio[4802]: info: ------------------------------ 7ms Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:24 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand play Sep 02 11:42:24 localhost volumio[4802]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 11:42:24 localhost volumio[4802]: info: ------------------------------ 83ms Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:24 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:24 localhost volumio[4802]: info: Sep 02 11:42:24 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:24 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:25 localhost volumio[4802]: info: Sep 02 11:42:25 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 1238ms Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand play took 1235 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 1232ms Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 1231ms Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:25 localhost volumio[4802]: info: Sep 02 11:42:25 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:25 localhost volumio[4802]: info: Sep 02 11:42:25 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:25 localhost volumio[4802]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Deep%20Side/Git%20Fresh/aa0ccf7b-fc30-4663-8e09-728286f859ab.jpg' Sep 02 11:42:25 localhost volumio[4802]: info: Sep 02 11:42:25 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand status took 12 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand status took 10 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:25 localhost volumio[4802]: info: Sep 02 11:42:25 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:25 localhost volumio[4802]: info: Sep 02 11:42:25 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand status took 17 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand status took 11 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:25 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:25 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:25 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:25 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:25 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 59ms Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 74ms Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 76ms Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 50 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 49 milliseconds Sep 02 11:42:25 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 49 milliseconds Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:25 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:25 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:25 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:25 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:25 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:25 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:25 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:25 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:25 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 136ms Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 129ms Sep 02 11:42:25 localhost volumio[4802]: info: ------------------------------ 123ms Sep 02 11:42:33 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand update Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:33 localhost volumio[4802]: info: Sep 02 11:42:33 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand update took 4 milliseconds Sep 02 11:42:33 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:33 localhost volumio[4802]: info: Sep 02 11:42:33 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:33 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:33 localhost volumio[4802]: info: Sep 02 11:42:33 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:33 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand status took 9 milliseconds Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand status took 8 milliseconds Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand status took 7 milliseconds Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand status took 5 milliseconds Sep 02 11:42:33 localhost volumio[4802]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:42:33 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: info: Command Router : Notfying DB Updatetrue Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:34 localhost volumio[4802]: info: Sep 02 11:42:34 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:34 localhost volumio[4802]: info: Sep 02 11:42:34 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:34 localhost volumio[4802]: info: Sep 02 11:42:34 localhost volumio[4802]: ---------------------------- MPD announces state update: update Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 94 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 43 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand status took 17 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand status took 15 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand status took 15 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand status took 13 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand status took 11 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand status took 10 milliseconds Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: info: Command Router : Notfying DB Updatefalse Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::Close All Modals sent Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9737,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:34 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:34 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9737,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:34 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:34 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9737,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:34 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:34 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: ------------------------------ 343ms Sep 02 11:42:34 localhost volumio[4802]: info: ------------------------------ 341ms Sep 02 11:42:34 localhost volumio[4802]: info: ------------------------------ 339ms Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 139 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 115 milliseconds Sep 02 11:42:34 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 93 milliseconds Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:34 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9851,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:34 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:34 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9851,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:34 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:34 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9851,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:34 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:34 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:34 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:34 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:34 localhost volumio[4802]: info: ------------------------------ 264ms Sep 02 11:42:34 localhost volumio[4802]: info: ------------------------------ 262ms Sep 02 11:42:34 localhost volumio[4802]: info: ------------------------------ 259ms Sep 02 11:42:39 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:39 localhost volumio[4802]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::ClearQueue Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::stPlaybackTimer Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:42:39 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:39 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:39 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:39 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::serviceStop Sep 02 11:42:39 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:39 localhost volumio[4802]: info: CoreCommandRouter::serviceStop Sep 02 11:42:39 localhost volumio[4802]: info: ControllerMpd::stop Sep 02 11:42:39 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:42:39 localhost volumio[4802]: info: CorePlayQueue::clearPlayQueue Sep 02 11:42:39 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:42:39 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:42:39 localhost volumio[4802]: info: CoreStateMachine::addQueueItems Sep 02 11:42:39 localhost volumio[4802]: info: CorePlayQueue::addQueueItems Sep 02 11:42:39 localhost volumio[4802]: info: Preload queue cleared Sep 02 11:42:39 localhost volumio[4802]: info: Adding Item to queue: music-library/NAS/NAS音乐/太多 - 陈冠蒲 Sep 02 11:42:39 localhost volumio[4802]: info: Exploding uri music-library/NAS/NAS音乐/太多 - 陈冠蒲 in service mpd Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:40 localhost volumio[4802]: info: ALBUMART /albumart?cacheid=665&web=%E9%99%88%E5%86%A0%E8%92%B2/%E5%B0%B1%E8%AE%A9%E4%BD%A0%E8%B5%B0/extralarge&path=%2Fmnt%2FNAS%2FNAS%E9%9F%B3%E4%B9%90%2F%E5%A4%AA%E5%A4%9A%20-%20%E9%99%88%E5%86%A0%E8%92%B2%2F%E5%A4%AA%E5%A4%9A%20-%20%E9%99%88%E5%86%A0%E8%92%B2.mp3&metadata=false Sep 02 11:42:40 localhost volumio[4802]: info: URI /mnt/NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3 Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand stop took 52 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::volumioPushQueue Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::saveQueue Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::updateTrackBlock Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrackBlock Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::volumioPlay Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::play index 0 Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::stop Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::play index undefined Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::startPlaybackTimer Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3 Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:40 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:40 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand status took 23 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand stop took 7 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:40 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand clear took 4 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3" Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:40 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:40 localhost volumio[4802]: 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":"Booty Music","artist":"Deep Side","album":"Git Fresh","uri":"NAS/NAS音乐/Booty Music - Deep Side/Booty Music - Deep Side.mp3","trackType":"mp3"} Sep 02 11:42:40 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::syncState stateService stop Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:40 localhost volumio[4802]: info: No code Sep 02 11:42:40 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:40 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:40 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:40 localhost volumio[4802]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 11:42:40 localhost volumio[4802]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 63ms Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 43ms Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 42ms Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:40 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:40 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:40 localhost volumio[4802]: error: updateQueue error: null Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:40 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 39ms Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand add "NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3" took 37 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 5ms Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 5ms Sep 02 11:42:40 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand play Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:40 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:40 localhost volumio[4802]: info: Sep 02 11:42:40 localhost volumio[4802]: ---------------------------- MPD announces system playlist update Sep 02 11:42:40 localhost volumio[4802]: info: Ignoring MPD Status Update Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 14ms Sep 02 11:42:40 localhost volumio[4802]: info: sendMpdCommand play took 12 milliseconds Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 12ms Sep 02 11:42:40 localhost volumio[4802]: info: ------------------------------ 10ms Sep 02 11:42:41 localhost volumio[4802]: info: Sep 02 11:42:41 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:41 localhost volumio[4802]: info: Sep 02 11:42:41 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:41 localhost volumio[4802]: info: Sep 02 11:42:41 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:41 localhost volumio[4802]: info: Sep 02 11:42:41 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand status took 7 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand status took 6 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand status took 4 milliseconds Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:41 localhost volumio[4802]: info: Sep 02 11:42:41 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:41 localhost volumio[4802]: info: Sep 02 11:42:41 localhost volumio[4802]: ---------------------------- MPD announces state update: player Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::getState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand status Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand status took 13 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand status took 8 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand status took 5 milliseconds Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseState Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"太多","artist":"陈冠蒲","album":"就让你走","uri":"NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3","trackType":"mp3"} Sep 02 11:42:41 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus stop Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"太多","artist":"陈冠蒲","album":"就让你走","uri":"NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3","trackType":"mp3"} Sep 02 11:42:41 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:41 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"太多","artist":"陈冠蒲","album":"就让你走","uri":"NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3","trackType":"mp3"} Sep 02 11:42:41 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:41 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: ------------------------------ 56ms Sep 02 11:42:41 localhost volumio[4802]: info: ------------------------------ 81ms Sep 02 11:42:41 localhost volumio[4802]: info: ------------------------------ 80ms Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 60 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 58 milliseconds Sep 02 11:42:41 localhost volumio[4802]: info: sendMpdCommand playlistinfo took 57 milliseconds Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:41 localhost volumio[4802]: verbose: ControllerMpd::parseTrackInfo Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"太多","artist":"陈冠蒲","album":"就让你走","uri":"NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3","trackType":"mp3"} Sep 02 11:42:41 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:41 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"太多","artist":"陈冠蒲","album":"就让你走","uri":"NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3","trackType":"mp3"} Sep 02 11:42:41 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:41 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: ControllerMpd::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::servicePushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"太多","artist":"陈冠蒲","album":"就让你走","uri":"NAS/NAS音乐/太多 - 陈冠蒲/太多 - 陈冠蒲.mp3","trackType":"mp3"} Sep 02 11:42:41 localhost volumio[4802]: verbose: CURRENT POSITION 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState stateService play Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::syncState currentStatus play Sep 02 11:42:41 localhost volumio[4802]: info: Received an update from plugin. extracting info from payload Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: CoreStateMachine::pushState Sep 02 11:42:41 localhost volumio[4802]: info: CorePlayQueue::getTrack 0 Sep 02 11:42:41 localhost volumio[4802]: info: CoreCommandRouter::volumioPushState Sep 02 11:42:41 localhost volumio[4802]: info: ------------------------------ 157ms Sep 02 11:42:41 localhost volumio[4802]: info: ------------------------------ 150ms Sep 02 11:42:41 localhost volumio[4802]: info: ------------------------------ 147ms Sep 02 11:42:44 localhost ntpd[776]: 84.16.67.12 local addr 192.168.2.104 -> Sep 02 11:42:53 localhost volumio[4802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:42:53 localhost volumio[4802]: Error: connect ETIMEDOUT 69.63.184.14:80 Sep 02 11:42:53 localhost volumio[4802]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 02 11:42:53 localhost volumio[4802]: errno: -110, Sep 02 11:42:53 localhost volumio[4802]: code: 'ETIMEDOUT', Sep 02 11:42:53 localhost volumio[4802]: syscall: 'connect', Sep 02 11:42:53 localhost volumio[4802]: address: '69.63.184.14', Sep 02 11:42:53 localhost volumio[4802]: port: 80 Sep 02 11:42:53 localhost volumio[4802]: } Sep 02 11:42:53 localhost volumio[4802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:42:54 localhost sudo[6667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 11:41 Sep 02 11:42:54 localhost sudo[6667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:42:54 localhost systemd[1]: Starting Cleanup of Temporary Directories... Sep 02 11:42:54 localhost systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Sep 02 11:42:54 localhost systemd[1]: Started Cleanup of Temporary Directories. PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"