-- Logs begin at Sat 2024-08-17 08:52:22 -08, end at Sat 2024-08-17 09:36:59 -08. -- Aug 17 09:35:11 volumio volumio[1037]: info: CoreCommandRouter::volumioGetQueue Aug 17 09:35:11 volumio volumio[1037]: info: CoreStateMachine::getQueue Aug 17 09:35:11 volumio volumio[1037]: info: CorePlayQueue::getQueue Aug 17 09:35:14 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 09:35:14 volumio volumio[1037]: info: CURURI: music-library Aug 17 09:35:14 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:19 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 09:35:19 volumio volumio[1037]: info: CURURI: music-library/NAS Aug 17 09:35:19 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 09:35:20 volumio volumio[1037]: info: CURURI: music-library/NAS/nas Aug 17 09:35:21 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:21 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/Sunflower.flac Aug 17 09:35:25 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/Sunflower.flac in service mpd Aug 17 09:35:26 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E6%9D%8E%E6%A3%AE%E8%8C%82/%E6%9D%8E%E6%A3%AE%E8%8C%82Sam%E7%9A%842019%E6%8C%87%E5%BC%B9%E6%94%B9%E7%BC%96%E4%BD%9C%E5%93%81%E9%9B%86/extralarge&path=%2Fmnt%2FNAS%2Fnas%2FSunflower.flac&metadata=false Aug 17 09:35:26 volumio volumio[1037]: info: URI /mnt/NAS/nas/Sunflower.flac Aug 17 09:35:32 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 09:35:32 volumio volumio[1037]: info: CURURI: music-library/NAS/nas/5七 里 香 Aug 17 09:35:32 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Preloading song: music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:32 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 in service mpd Aug 17 09:35:32 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F03.%20%E8%97%89%20%E5%8F%A3.mp3&metadata=false Aug 17 09:35:32 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::ClearQueue Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::stop Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::serviceStop Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::serviceStop Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::stop Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::clearPlayQueue Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::saveQueue Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::addQueueItems Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::addQueueItems Aug 17 09:35:33 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: Exploding uri music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 in service mpd Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F09.%20%E5%9C%92%20%E6%B8%B8%20%E6%9C%83.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F10.%20%E6%AD%A2%20%E6%88%B0%E4%B9%8B%20%E6%AE%A4.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F01.%20%E6%88%91%20%E7%9A%84%E5%9C%B0%20%E7%9B%A4.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F08.%20%E5%9B%B0%20%E7%8D%B8%E4%B9%8B%20%E6%96%97.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F07.%20%E4%BA%82%20%E8%88%9E%E6%98%A5%20%E7%A7%8B.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F04.%20%E5%A4%96%20%E5%A9%86.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F02.%20%E4%B8%83%20%E9%87%8C%20%E9%A6%99.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F05.%20%E5%B0%87%20%E8%BB%8D.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F06.%20%E6%93%B1%20%E6%B7%BA.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand stop took 11 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F09.%20%E5%9C%92%20%E6%B8%B8%20%E6%9C%83.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F10.%20%E6%AD%A2%20%E6%88%B0%E4%B9%8B%20%E6%AE%A4.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F01.%20%E6%88%91%20%E7%9A%84%E5%9C%B0%20%E7%9B%A4.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F08.%20%E5%9B%B0%20%E7%8D%B8%E4%B9%8B%20%E6%96%97.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F07.%20%E4%BA%82%20%E8%88%9E%E6%98%A5%20%E7%A7%8B.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F04.%20%E5%A4%96%20%E5%A9%86.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F02.%20%E4%B8%83%20%E9%87%8C%20%E9%A6%99.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F05.%20%E5%B0%87%20%E8%BB%8D.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ALBUMART /albumart?cacheid=337&web=%E5%91%A8%E6%9D%B0%E5%80%AB/%E4%B8%83%E9%87%8C%E9%A6%99/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F5%E4%B8%83%20%E9%87%8C%20%E9%A6%99%2F06.%20%E6%93%B1%20%E6%B7%BA.mp3&metadata=false Aug 17 09:35:33 volumio volumio[1037]: info: URI /mnt/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::saveQueue Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPlay Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::play index 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::stop Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::play index undefined Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::startPlaybackTimer Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 10 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand stop took 5 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 4 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: error: Upnp client error: Error: This socket has been ended by the other party Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand clear took 1 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/5七 里 香/08. 困 獸之 斗.mp3" Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sunflower纯高潮版（翻自 孙培博） ","artist":"李森茂","album":"李森茂Sam的2019指弹改编作品集","uri":"USB/_¼Ӿ_/Sunflower.flac","trackType":"flac"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: No code Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 19ms Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:33 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:33 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand add "NAS/nas/5七 里 香/08. 困 獸之 斗.mp3" took 6 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand play Aug 17 09:35:33 volumio volumio[1037]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 09:35:33 volumio volumio[1037]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 14ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 13ms Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:33 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:33 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 4ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 4ms Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand play took 3 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 2ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 2ms Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:33 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:33 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 0ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 1ms Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: Aug 17 09:35:33 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 4 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 3 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"困獸之斗","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/08. 困 獸之 斗.mp3","trackType":"mp3"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"困獸之斗","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/08. 困 獸之 斗.mp3","trackType":"mp3"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:33 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"困獸之斗","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/08. 困 獸之 斗.mp3","trackType":"mp3"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:33 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"困獸之斗","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/08. 困 獸之 斗.mp3","trackType":"mp3"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:33 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"困獸之斗","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/08. 困 獸之 斗.mp3","trackType":"mp3"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:33 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 22ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 25ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 24ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 23ms Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 21ms Aug 17 09:35:33 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 17 09:35:33 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:33 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"困獸之斗","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/08. 困 獸之 斗.mp3","trackType":"mp3"} Aug 17 09:35:33 volumio volumio[1037]: verbose: CURRENT POSITION 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:33 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:33 volumio volumio[1037]: info: ------------------------------ 28ms Aug 17 09:35:33 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 17 09:35:33 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 17 09:35:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:40 volumio volumio[1037]: info: VolumeController::SetAlsaVolume52 Aug 17 09:35:40 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:40 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:40 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:40 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:42 volumio volumio[1037]: info: VolumeController::SetAlsaVolume62 Aug 17 09:35:42 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:42 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:42 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:42 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:42 volumio volumio[1037]: info: VolumeController::SetAlsaVolume61 Aug 17 09:35:42 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:42 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:42 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:42 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::ClearQueue Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::stop Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::serviceStop Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 4 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::serviceStop Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::stop Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::clearPlayQueue Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::saveQueue Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::addQueueItems Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::addQueueItems Aug 17 09:35:52 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::saveQueue Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPlay Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::play index 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::stop Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::play index undefined Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::startPlaybackTimer Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand stop took 17 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand stop took 4 milliseconds Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:52 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 3 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand clear took 2 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/5七 里 香/03. 藉 口.mp3" Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: No code Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: No code Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 17ms Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 16ms Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:52 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:52 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:52 volumio volumio[1037]: error: updateQueue error: null Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:52 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 17ms Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand add "NAS/nas/5七 里 香/03. 藉 口.mp3" took 16 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 2ms Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 1ms Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand play Aug 17 09:35:52 volumio volumio[1037]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 22ms Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:52 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:52 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 6ms Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand play took 5 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 3ms Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: Aug 17 09:35:52 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 4 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 6 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 4 milliseconds Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand status took 19 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藉口","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/03. 藉 口.mp3","trackType":"mp3"} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藉口","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/03. 藉 口.mp3","trackType":"mp3"} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:52 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藉口","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/03. 藉 口.mp3","trackType":"mp3"} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:52 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藉口","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/03. 藉 口.mp3","trackType":"mp3"} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:52 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藉口","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/03. 藉 口.mp3","trackType":"mp3"} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:52 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 48ms Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 52ms Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 52ms Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 51ms Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 51ms Aug 17 09:35:52 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 17 09:35:52 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:52 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"藉口","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/03. 藉 口.mp3","trackType":"mp3"} Aug 17 09:35:52 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:52 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:52 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:52 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:52 volumio volumio[1037]: info: ------------------------------ 60ms Aug 17 09:35:57 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::ClearQueue Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::stop Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::serviceStop Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::serviceStop Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::stop Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::clearPlayQueue Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::saveQueue Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::addQueueItems Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::addQueueItems Aug 17 09:35:57 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/03. 藉 口.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/09. 園 游 會.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/10. 止 戰之 殤.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/01. 我 的地 盤.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/08. 困 獸之 斗.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/07. 亂 舞春 秋.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/04. 外 婆.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/05. 將 軍.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Adding Item to queue: music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: Using cached record of: music-library/NAS/nas/5七 里 香/06. 擱 淺.mp3 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::saveQueue Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPlay Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::play index 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::stop Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::play index undefined Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::startPlaybackTimer Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/5七 里 香/02. 七 里 香.mp3 Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand stop took 14 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand stop took 3 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:57 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand clear took 2 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/5七 里 香/02. 七 里 香.mp3" Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: No code Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: No code Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 14ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 14ms Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:57 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:57 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:57 volumio volumio[1037]: error: updateQueue error: null Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:57 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 18ms Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand add "NAS/nas/5七 里 香/02. 七 里 香.mp3" took 18 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 6ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 5ms Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand play Aug 17 09:35:57 volumio volumio[1037]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 23ms Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:57 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Aug 17 09:35:57 volumio volumio[1037]: info: Ignoring MPD Status Update Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 11ms Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand play took 8 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 7ms Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: Aug 17 09:35:57 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand status took 3 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 09:35:57 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:57 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:57 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:57 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:57 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:57 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:35:57 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 41ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 47ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 48ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 47ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 47ms Aug 17 09:35:57 volumio volumio[1037]: info: ------------------------------ 46ms Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::volumioPause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::servicePause Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::servicePause Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::pause Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand pause Aug 17 09:35:59 volumio volumio[1037]: info: Aug 17 09:35:59 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand pause took 1 milliseconds Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:59 volumio volumio[1037]: info: Aug 17 09:35:59 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:59 volumio volumio[1037]: info: Aug 17 09:35:59 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 09:35:59 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:59 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3232,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:59 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::syncState stateService pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3232,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:59 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::syncState stateService pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:59 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3232,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:35:59 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::syncState stateService pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:35:59 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:35:59 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:35:59 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:35:59 volumio volumio[1037]: info: ------------------------------ 8ms Aug 17 09:35:59 volumio volumio[1037]: info: ------------------------------ 8ms Aug 17 09:35:59 volumio volumio[1037]: info: ------------------------------ 8ms Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPlay Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::play index undefined Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::startPlaybackTimer Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::resume Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand play Aug 17 09:36:09 volumio volumio[1037]: info: Aug 17 09:36:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand play took 3 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:36:09 volumio volumio[1037]: info: Aug 17 09:36:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:36:09 volumio volumio[1037]: info: Aug 17 09:36:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand status took 3 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4724,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:36:09 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4724,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:36:09 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:36:09 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: ------------------------------ 21ms Aug 17 09:36:09 volumio volumio[1037]: info: ------------------------------ 19ms Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4724,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:36:09 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Aug 17 09:36:09 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: ------------------------------ 22ms Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::servicePause Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePause Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pause Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand pause Aug 17 09:36:09 volumio volumio[1037]: info: Aug 17 09:36:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand pause took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:36:09 volumio volumio[1037]: info: Aug 17 09:36:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:36:09 volumio volumio[1037]: info: Aug 17 09:36:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::getState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand status took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:36:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5224,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:36:09 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5224,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:36:09 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:36:09 volumio volumio[1037]: info: ControllerMpd::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5224,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"七里香","artist":"周杰倫","album":"七里香","uri":"NAS/nas/5七 里 香/02. 七 里 香.mp3","trackType":"mp3"} Aug 17 09:36:09 volumio volumio[1037]: verbose: CURRENT POSITION 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus pause Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 17 09:36:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 17 09:36:09 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Aug 17 09:36:09 volumio volumio[1037]: info: ------------------------------ 15ms Aug 17 09:36:09 volumio volumio[1037]: info: ------------------------------ 14ms Aug 17 09:36:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 09:36:18 volumio volumio[1037]: info: CURURI: artists:// Aug 17 09:36:18 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:24 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 09:36:24 volumio volumio[1037]: info: CURURI: albums:// Aug 17 09:36:24 volumio volumio[1037]: info: listAlbums - loading Albums from cache Aug 17 09:36:24 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:27 volumio ntpd[3056]: 84.16.67.12 local addr 192.168.1.21 -> Aug 17 09:36:32 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 17 09:36:32 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 09:36:32 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 17 09:36:36 volumio volumio[1037]: info: Getting webradio selection Aug 17 09:36:37 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:38 volumio dhcpcd[746]: wlan0: carrier lost Aug 17 09:36:38 volumio kernel: wlan0: disassociated from b8:45:f4:a4:1b:ac (Reason: 1=UNSPECIFIED) Aug 17 09:36:38 volumio avahi-daemon[681]: Withdrawing address record for 192.168.1.21 on wlan0. Aug 17 09:36:38 volumio avahi-daemon[681]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.21. Aug 17 09:36:38 volumio avahi-daemon[681]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 17 09:36:38 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b8:45:f4:a4:1b:ac reason=1 Aug 17 09:36:38 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 17 09:36:38 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Aug 17 09:36:38 volumio volumio[1037]: info: Discovery: A device disappeared from network Aug 17 09:36:38 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated Aug 17 09:36:38 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement) Aug 17 09:36:38 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Aug 17 09:36:38 volumio volumio[1037]: info: Discovery: Device volumio disappeared from network Aug 17 09:36:38 volumio dhcpcd[746]: wlan0: deleting route to 192.168.1.0/24 Aug 17 09:36:38 volumio dhcpcd[746]: wlan0: deleting default route via 192.168.1.1 Aug 17 09:36:39 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN Aug 17 09:36:39 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN Aug 17 09:36:39 volumio ntpd[3056]: Deleting interface #3 wlan0, 192.168.1.21#123, interface stats: received=216, sent=229, dropped=0, active_time=1306 secs Aug 17 09:36:39 volumio ntpd[3056]: 119.28.206.193 local addr 192.168.1.21 -> Aug 17 09:36:39 volumio ntpd[3056]: 202.112.29.82 local addr 192.168.1.21 -> Aug 17 09:36:39 volumio ntpd[3056]: 78.46.102.180 local addr 192.168.1.21 -> Aug 17 09:36:39 volumio ntpd[3056]: 119.28.183.184 local addr 192.168.1.21 -> Aug 17 09:36:39 volumio ntpd[3056]: 84.16.73.33 local addr 192.168.1.21 -> Aug 17 09:36:39 volumio ntpd[3056]: 202.112.31.197 local addr 192.168.1.21 -> Aug 17 09:36:39 volumio ntpd[3056]: 193.182.111.12 local addr 192.168.1.21 -> Aug 17 09:36:40 volumio wpa_supplicant[946]: wlan0: SME: Trying to authenticate with b8:45:f4:a4:1b:ac (SSID='TP-LINK_053A_5G' freq=5180 MHz) Aug 17 09:36:40 volumio kernel: wlan0: authenticate with b8:45:f4:a4:1b:ac Aug 17 09:36:40 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Aug 17 09:36:40 volumio kernel: wlan0: send auth to b8:45:f4:a4:1b:ac (try 1/3) Aug 17 09:36:40 volumio kernel: wlan0: send auth to b8:45:f4:a4:1b:ac (try 2/3) Aug 17 09:36:40 volumio kernel: wlan0: send auth to b8:45:f4:a4:1b:ac (try 3/3) Aug 17 09:36:40 volumio kernel: wlan0: authentication with b8:45:f4:a4:1b:ac timed out Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: SME: Trying to authenticate with b8:45:f4:a4:1b:ac (SSID='TP-LINK_053A_5G' freq=5180 MHz) Aug 17 09:36:43 volumio kernel: wlan0: authenticate with b8:45:f4:a4:1b:ac Aug 17 09:36:43 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Aug 17 09:36:43 volumio kernel: wlan0: send auth to b8:45:f4:a4:1b:ac (try 1/3) Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: Trying to associate with b8:45:f4:a4:1b:ac (SSID='TP-LINK_053A_5G' freq=5180 MHz) Aug 17 09:36:43 volumio kernel: wlan0: authenticated Aug 17 09:36:43 volumio kernel: wlan0: associate with b8:45:f4:a4:1b:ac (try 1/3) Aug 17 09:36:43 volumio kernel: wlan0: RX AssocResp from b8:45:f4:a4:1b:ac (capab=0x1511 status=0 aid=2) Aug 17 09:36:43 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated Aug 17 09:36:43 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Aug 17 09:36:43 volumio kernel: wlan0: associated Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: Associated with b8:45:f4:a4:1b:ac Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: WPA: Key negotiation completed with b8:45:f4:a4:1b:ac [PTK=CCMP GTK=TKIP] Aug 17 09:36:43 volumio wpa_supplicant[946]: wlan0: CTRL-EVENT-CONNECTED - Connection to b8:45:f4:a4:1b:ac completed [id=0 id_str=] Aug 17 09:36:43 volumio dhcpcd[746]: wlan0: carrier acquired Aug 17 09:36:43 volumio dhcpcd[746]: wlan0: IAID 4b:09:0f:44 Aug 17 09:36:43 volumio kernel: wlan0: Limiting TX power to 27 (30 - 3) dBm as advertised by b8:45:f4:a4:1b:ac Aug 17 09:36:44 volumio dhcpcd[746]: wlan0: rebinding lease of 192.168.1.21 Aug 17 09:36:44 volumio dhcpcd[746]: wlan0: probing address 192.168.1.21/24 Aug 17 09:36:44 volumio dhcpcd[746]: wlan0: soliciting an IPv6 router Aug 17 09:36:48 volumio dhcpcd[746]: wlan0: leased 192.168.1.21 for 86400 seconds Aug 17 09:36:48 volumio dhcpcd[746]: wlan0: adding route to 192.168.1.0/24 Aug 17 09:36:48 volumio dhcpcd[746]: wlan0: adding default route via 192.168.1.1 Aug 17 09:36:48 volumio avahi-daemon[681]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.21. Aug 17 09:36:48 volumio avahi-daemon[681]: New relevant interface wlan0.IPv4 for mDNS. Aug 17 09:36:48 volumio avahi-daemon[681]: Registering new address record for 192.168.1.21 on wlan0.IPv4. Aug 17 09:36:48 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement) Aug 17 09:36:48 volumio ntpd[3056]: ntpd exiting on signal 15 (Terminated) Aug 17 09:36:48 volumio systemd[1]: Stopping Network Time Service... Aug 17 09:36:48 volumio systemd[1]: ntp.service: Succeeded. Aug 17 09:36:48 volumio systemd[1]: Stopped Network Time Service. Aug 17 09:36:48 volumio systemd[1]: Starting Network Time Service... Aug 17 09:36:48 volumio ntpd[4567]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 17 09:36:48 volumio ntpd[4567]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 17 09:36:48 volumio systemd[1]: Started Network Time Service. Aug 17 09:36:48 volumio ntpd[4573]: proto: precision = 1.480 usec (-19) Aug 17 09:36:48 volumio ntpd[4573]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 17 09:36:48 volumio ntpd[4573]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 17 09:36:48 volumio ntpd[4573]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 599 days ago Aug 17 09:36:48 volumio ntpd[4573]: Listen and drop on 0 v6wildcard [::]:123 Aug 17 09:36:48 volumio ntpd[4573]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 17 09:36:48 volumio ntpd[4573]: Listen normally on 2 lo 127.0.0.1:123 Aug 17 09:36:48 volumio ntpd[4573]: Listen normally on 3 wlan0 192.168.1.21:123 Aug 17 09:36:48 volumio ntpd[4573]: Listening on routing socket on fd #20 for interface updates Aug 17 09:36:48 volumio ntpd[4573]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 17 09:36:48 volumio ntpd[4573]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 17 09:36:49 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 09:36:49 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:49 volumio ntpd[4573]: Soliciting pool server 202.112.29.82 Aug 17 09:36:50 volumio ntpd[4573]: Soliciting pool server 162.159.200.1 Aug 17 09:36:50 volumio ntpd[4573]: Soliciting pool server 108.59.2.24 Aug 17 09:36:51 volumio ntpd[4573]: Soliciting pool server 185.209.85.222 Aug 17 09:36:51 volumio ntpd[4573]: Soliciting pool server 117.80.231.60 Aug 17 09:36:51 volumio ntpd[4573]: Soliciting pool server 78.46.102.180 Aug 17 09:36:51 volumio volumio[1037]: info: Discovery: adding aba99887-1bb9-4b7b-9aee-7c58e8351450 Aug 17 09:36:51 volumio volumio[1037]: info: Discovery: Found device Volumio Aug 17 09:36:51 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 17 09:36:51 volumio volumio[1037]: info: CorePlayQueue::getTrack 7 Aug 17 09:36:52 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 09:36:52 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:52 volumio ntpd[4573]: Soliciting pool server 84.16.67.12 Aug 17 09:36:52 volumio ntpd[4573]: Soliciting pool server 162.159.200.123 Aug 17 09:36:52 volumio ntpd[4573]: Soliciting pool server 193.182.111.143 Aug 17 09:36:52 volumio ntpd[4573]: Soliciting pool server 202.112.31.197 Aug 17 09:36:53 volumio ntpd[4573]: Soliciting pool server 202.118.1.81 Aug 17 09:36:53 volumio ntpd[4573]: Soliciting pool server 193.182.111.12 Aug 17 09:36:53 volumio ntpd[4573]: Soliciting pool server 119.28.206.193 Aug 17 09:36:54 volumio ntpd[4573]: Soliciting pool server 124.65.131.109 Aug 17 09:36:54 volumio ntpd[4573]: Soliciting pool server 2402:f000:1:416:101:6:6:172 Aug 17 09:36:54 volumio ntpd[4573]: Soliciting pool server 84.16.73.33 Aug 17 09:36:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 09:36:55 volumio ntpd[4573]: Soliciting pool server 119.28.183.184 Aug 17 09:36:55 volumio volumio[1037]: info: Preload queue cleared Aug 17 09:36:58 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 09:36:59 volumio volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 09:36:59 volumio volumio[1037]: TypeError: Cannot read property 'length' of undefined Aug 17 09:36:59 volumio volumio[1037]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 17 09:36:59 volumio volumio[1037]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 17 09:36:59 volumio volumio[1037]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 17 09:36:59 volumio volumio[1037]: at Parser.emit (events.js:400:28) Aug 17 09:36:59 volumio volumio[1037]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 17 09:36:59 volumio volumio[1037]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 17 09:36:59 volumio volumio[1037]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 17 09:36:59 volumio volumio[1037]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 17 09:36:59 volumio volumio[1037]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 17 09:36:59 volumio volumio[1037]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 17 09:36:59 volumio volumio[1037]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 17 09:36:59 volumio volumio[1037]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 17 09:36:59 volumio volumio[1037]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 17 09:36:59 volumio volumio[1037]: at IncomingMessage.emit (events.js:412:35) Aug 17 09:36:59 volumio volumio[1037]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 17 09:36:59 volumio volumio[1037]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 17 09:36:59 volumio volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 09:36:59 volumio kernel: perf: interrupt took too long (2504 > 2500), lowering kernel.perf_event_max_sample_rate to 79750 Aug 17 09:36:59 volumio sudo[4587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 09:35 Aug 17 09:36:59 volumio sudo[4587]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"