-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-09-02 22:22:05 CST. -- Sep 02 22:21:00 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 22:21:00 volumio volumio[818]: info: CURURI: music-library/USB Sep 02 22:21:00 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:01 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 22:21:01 volumio volumio[818]: info: CURURI: music-library/USB/5804-BE48 Sep 02 22:21:02 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:02 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 22:21:02 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 22:21:02 volumio volumio[818]: info: Discovery: Getting this device information Sep 02 22:21:02 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Sep 02 22:21:02 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:02 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 22:21:03 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 22:21:03 volumio volumio[818]: info: CURURI: music-library/USB/5804-BE48/歌曲 Sep 02 22:21:03 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:04 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 22:21:04 volumio volumio[818]: info: CURURI: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram] Sep 02 22:21:04 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav Sep 02 22:21:04 volumio volumio[818]: info: Preloading song: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav Sep 02 22:21:04 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape in service mpd Sep 02 22:21:04 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=//extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2FCDImage.ape&metadata=false Sep 02 22:21:04 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:04 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav in service mpd Sep 02 22:21:04 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E5%A4%A9%E5%A0%82.wav&metadata=false Sep 02 22:21:04 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav Sep 02 22:21:04 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav in service mpd Sep 02 22:21:04 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E5%93%AD%E7%A0%82.wav&metadata=false Sep 02 22:21:04 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=The%20Police/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2FThe%20Police.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=Kenny%20G/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2FGoing%20home.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E6%8B%89%E5%BE%B7%E6%96%AF%E5%9F%BA%E8%BF%9B%E8%A1%8C%E6%9B%B2.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E6%B8%A1%E5%8F%A3.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E5%8A%A0%E5%B7%9E%E6%97%85%E9%A6%86.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E8%91%AC%E5%BF%83.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E5%81%8F%E5%81%8F%E5%96%9C%E6%AC%A2%E4%BD%A0.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=Toni%20Braxtor/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2FLet%20It%20Flow.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E6%9C%88%E4%BA%AE%E4%BB%A3%E8%A1%A8%E6%88%91%E7%9A%84%E5%BF%83.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E6%81%B0%E4%BC%BC%E4%BD%A0%E7%9A%84%E6%B8%A9%E6%9F%94.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=%3F%3F%3F/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E4%BC%A4%E7%97%95.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav Sep 02 22:21:05 volumio volumio[818]: info: Exploding uri music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav in service mpd Sep 02 22:21:05 volumio volumio[818]: info: ALBUMART /albumart?cacheid=241&web=Kari%20Bremnes/T200A%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2F5804-BE48%2F%E6%AD%8C%E6%9B%B2%2F1993-%E5%90%BB%E5%88%AB%5BPolyGram%5D%2F%E6%9F%8F%E6%9E%97%E6%81%8B%E4%BA%BA.wav&metadata=false Sep 02 22:21:05 volumio volumio[818]: info: URI /mnt/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav Sep 02 22:21:07 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:07 volumio volumio[818]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::ClearQueue Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::clearPlayQueue Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::saveQueue Sep 02 22:21:07 volumio volumio[818]: info: CoreCommandRouter::volumioPushQueue Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::addQueueItems Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::addQueueItems Sep 02 22:21:07 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:07 volumio volumio[818]: info: CoreCommandRouter::volumioPushQueue Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::saveQueue Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:07 volumio volumio[818]: info: CoreCommandRouter::volumioPlay Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::play index 0 Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::addQueueItems Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::addQueueItems Sep 02 22:21:07 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav Sep 02 22:21:07 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav Sep 02 22:21:07 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:07 volumio volumio[818]: info: CoreCommandRouter::volumioPushQueue Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::saveQueue Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::play index undefined Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:07 volumio volumio[818]: info: CoreStateMachine::startPlaybackTimer Sep 02 22:21:07 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:07 volumio volumio[818]: verbose: ControllerMpd::clearAddPlayTracks USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:07 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:07 volumio volumio[818]: info: sendMpdCommand stop took 4 milliseconds Sep 02 22:21:07 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 22:21:07 volumio volumio[818]: info: sendMpdCommand clear took 0 milliseconds Sep 02 22:21:07 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape" Sep 02 22:21:07 volumio volumio[818]: info: Sep 02 22:21:07 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:07 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:07 volumio volumio[818]: info: Sep 02 22:21:07 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:07 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:07 volumio volumio[818]: info: sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape" took 2 milliseconds Sep 02 22:21:07 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand play Sep 02 22:21:07 volumio volumio[818]: info: Sep 02 22:21:07 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:07 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:07 volumio volumio[818]: info: Sep 02 22:21:07 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:07 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:07 volumio volumio[818]: info: ------------------------------ 8ms Sep 02 22:21:07 volumio volumio[818]: info: ------------------------------ 7ms Sep 02 22:21:07 volumio volumio[818]: info: sendMpdCommand play took 7 milliseconds Sep 02 22:21:07 volumio volumio[818]: info: ------------------------------ 6ms Sep 02 22:21:07 volumio volumio[818]: info: ------------------------------ 5ms Sep 02 22:21:07 volumio volumio[818]: info: Sep 02 22:21:07 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:07 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:07 volumio volumio[818]: info: Sep 02 22:21:07 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:07 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:07 volumio volumio[818]: info: ------------------------------ 3ms Sep 02 22:21:07 volumio volumio[818]: info: ------------------------------ 3ms Sep 02 22:21:07 volumio volumio[818]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 02 22:21:07 volumio volumio[818]: info: Retrying Login Due to Network Error Sep 02 22:21:08 volumio volumio[818]: info: Sep 02 22:21:08 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:08 volumio volumio[818]: info: Sep 02 22:21:08 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:08 volumio volumio[818]: info: Sep 02 22:21:08 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:08 volumio volumio[818]: info: Sep 02 22:21:08 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand status took 7 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand status took 6 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand status took 5 milliseconds Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:08 volumio volumio[818]: info: Sep 02 22:21:08 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:08 volumio volumio[818]: info: Sep 02 22:21:08 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand status took 9 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand status took 5 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:08 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:08 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:08 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:08 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:08 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: ------------------------------ 44ms Sep 02 22:21:08 volumio volumio[818]: info: ------------------------------ 69ms Sep 02 22:21:08 volumio volumio[818]: info: ------------------------------ 69ms Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand playlistinfo took 55 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand playlistinfo took 51 milliseconds Sep 02 22:21:08 volumio volumio[818]: info: sendMpdCommand playlistinfo took 52 milliseconds Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:08 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:08 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:08 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:08 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:08 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:08 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:08 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:08 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:08 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 ae 23 20 00 00 f0 00 Sep 02 22:21:08 volumio kernel: critical medium error, dev sda, sector 44966688 op 0x0:(READ) flags 0x84700 phys_seg 2 prio class 2 Sep 02 22:21:08 volumio volumio[818]: info: ------------------------------ 139ms Sep 02 22:21:08 volumio volumio[818]: info: ------------------------------ 134ms Sep 02 22:21:08 volumio volumio[818]: info: ------------------------------ 133ms Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 ae 23 68 00 00 08 00 Sep 02 22:21:08 volumio kernel: critical medium error, dev sda, sector 44966760 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 ae 23 70 00 00 08 00 Sep 02 22:21:08 volumio kernel: critical medium error, dev sda, sector 44966768 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:08 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 ae 23 78 00 00 08 00 Sep 02 22:21:08 volumio kernel: critical medium error, dev sda, sector 44966776 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:09 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::ClearQueue Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::serviceStop Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::serviceStop Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::stop Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::clearPlayQueue Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::saveQueue Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushQueue Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::addQueueItems Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::addQueueItems Sep 02 22:21:09 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushQueue Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::saveQueue Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPlay Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::play index 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::addQueueItems Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::addQueueItems Sep 02 22:21:09 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/天堂.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/哭砂.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/The Police.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Going home.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/渡口.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/葬心.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/偏偏喜欢你.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/Let It Flow.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/月亮代表我的心.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/恰似你的温柔.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/伤痕.wav Sep 02 22:21:09 volumio volumio[818]: info: Adding Item to queue: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav Sep 02 22:21:09 volumio volumio[818]: info: Using cached record of: music-library/USB/5804-BE48/歌曲/1993-吻别[PolyGram]/柏林恋人.wav Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushQueue Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::saveQueue Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::play index undefined Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::startPlaybackTimer Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::clearAddPlayTracks USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand stop took 47 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand stop took 10 milliseconds Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:09 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 7 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand clear took 6 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape" Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: No code Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: No code Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 66ms Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 66ms Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:09 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:09 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:09 volumio volumio[818]: error: updateQueue error: null Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:09 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 70ms Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape" took 68 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 9ms Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 8ms Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand play Sep 02 22:21:09 volumio volumio[818]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 83ms Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:09 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:09 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 22ms Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand play took 16 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 15ms Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 14ms Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 6 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 5 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: Sep 02 22:21:09 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 5 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:09 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:09 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:09 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 48ms Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 92ms Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 90ms Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 90ms Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 76 milliseconds Sep 02 22:21:09 volumio volumio[818]: info: sendMpdCommand playlistinfo took 76 milliseconds Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:09 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:09 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:09 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:09 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:09 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 128ms Sep 02 22:21:09 volumio volumio[818]: info: ------------------------------ 127ms Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::volumioPause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::servicePause Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::servicePause Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::pause Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand pause Sep 02 22:21:11 volumio volumio[818]: info: Sep 02 22:21:11 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand pause took 4 milliseconds Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:11 volumio volumio[818]: info: Sep 02 22:21:11 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:11 volumio volumio[818]: info: Sep 02 22:21:11 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand status took 6 milliseconds Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand status took 2 milliseconds Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 02 22:21:11 volumio volumio[818]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:11 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3088,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:11 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::syncState stateService pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3088,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:11 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::syncState stateService pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:11 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3088,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:11 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::syncState stateService pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:11 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:11 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:11 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:11 volumio volumio[818]: info: ------------------------------ 55ms Sep 02 22:21:11 volumio volumio[818]: info: ------------------------------ 54ms Sep 02 22:21:11 volumio volumio[818]: info: ------------------------------ 53ms Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Sep 02 22:21:13 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:13 volumio sudo[3614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 22:21:13 volumio sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 22:21:13 volumio sudo[3614]: pam_unix(sudo:session): session closed for user root Sep 02 22:21:13 volumio sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 22:21:13 volumio sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 22:21:13 volumio sudo[3616]: pam_unix(sudo:session): session closed for user root Sep 02 22:21:13 volumio volumio[818]: verbose: New Socket.io Connection to 192.168.5.92 from 192.168.5.49 UA: Mozilla/5.0 (Linux; Android 15; 23127PN0CC Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.144 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Sep 02 22:21:13 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 02 22:21:13 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 22:21:14 volumio sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 22:21:14 volumio sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 22:21:14 volumio sudo[3620]: pam_unix(sudo:session): session closed for user root Sep 02 22:21:14 volumio sudo[3622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 22:21:14 volumio sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 22:21:14 volumio sudo[3622]: pam_unix(sudo:session): session closed for user root Sep 02 22:21:14 volumio volumio[818]: verbose: New Socket.io Connection to 192.168.5.92 from 192.168.5.49 UA: Mozilla/5.0 (Linux; Android 15; 23127PN0CC Build/AQ3A.240627.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.144 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 02 22:21:14 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Sep 02 22:21:14 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:14 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 22:21:14 volumio volumio[818]: info: Listing playlists Sep 02 22:21:14 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 02 22:21:14 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 02 22:21:14 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 02 22:21:14 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 22:21:17 volumio volumio[818]: info: MyVolumio login type: Token Sep 02 22:21:18 volumio volumio[818]: info: CoreCommandRouter::volumioGetQueue Sep 02 22:21:18 volumio volumio[818]: info: CoreStateMachine::getQueue Sep 02 22:21:18 volumio volumio[818]: info: CorePlayQueue::getQueue Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::volumioPlay Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::play index undefined Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::startPlaybackTimer Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::resume Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand play Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand play took 5 milliseconds Sep 02 22:21:28 volumio volumio[818]: info: Sep 02 22:21:28 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:28 volumio volumio[818]: info: Sep 02 22:21:28 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:28 volumio volumio[818]: info: Sep 02 22:21:28 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand status took 2 milliseconds Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 22:21:28 volumio volumio[818]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:28 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4072,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:28 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4072,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:28 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:28 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:28 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4072,"duration":2884,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/CDImage.ape","trackType":"ape"} Sep 02 22:21:28 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:28 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:28 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:28 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:28 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:28 volumio volumio[818]: info: ------------------------------ 75ms Sep 02 22:21:28 volumio volumio[818]: info: ------------------------------ 69ms Sep 02 22:21:28 volumio volumio[818]: info: ------------------------------ 69ms Sep 02 22:21:29 volumio volumio[818]: info: CoreCommandRouter::volumioPause Sep 02 22:21:29 volumio volumio[818]: info: CoreStateMachine::pause Sep 02 22:21:29 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:29 volumio volumio[818]: info: CoreStateMachine::servicePause Sep 02 22:21:29 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:29 volumio volumio[818]: info: CoreCommandRouter::servicePause Sep 02 22:21:29 volumio volumio[818]: info: ControllerMpd::pause Sep 02 22:21:29 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand pause Sep 02 22:21:30 volumio volumio[818]: info: sendMpdCommand pause took 644 milliseconds Sep 02 22:21:30 volumio volumio[818]: info: Sep 02 22:21:30 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:30 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:30 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:30 volumio volumio[818]: info: Sep 02 22:21:30 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:30 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:30 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:30 volumio volumio[818]: info: Sep 02 22:21:30 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:30 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:30 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:30 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:30 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:30 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:30 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:30 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:30 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:30 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:30 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:30 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:30 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:30 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:30 volumio volumio[818]: verbose: CURRENT POSITION 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:30 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:30 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:30 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:30 volumio volumio[818]: info: ------------------------------ 81ms Sep 02 22:21:30 volumio volumio[818]: info: ------------------------------ 81ms Sep 02 22:21:30 volumio volumio[818]: info: ------------------------------ 80ms Sep 02 22:21:32 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 22:21:32 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 22:21:32 volumio volumio[818]: info: Discovery: Getting this device information Sep 02 22:21:32 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Sep 02 22:21:32 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:32 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPlay Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::play index 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::serviceStop Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 0 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::serviceStop Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::stop Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand stop took 7 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::play index undefined Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::startPlaybackTimer Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::clearAddPlayTracks USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand stop took 1 milliseconds Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand clear took 0 milliseconds Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav" Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:45 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:45 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav" took 2 milliseconds Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand play Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:45 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:45 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 12ms Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 11ms Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand play took 10 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 6ms Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 5ms Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:45 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:45 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:45 volumio volumio[818]: info: Sep 02 22:21:45 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand status took 8 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand status took 7 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 6ms Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand status took 6 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 5ms Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 02 22:21:45 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:45 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"Smile","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav","trackType":"wav"} Sep 02 22:21:45 volumio volumio[818]: verbose: CURRENT POSITION 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"Smile","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav","trackType":"wav"} Sep 02 22:21:45 volumio volumio[818]: verbose: CURRENT POSITION 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:45 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"Smile","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav","trackType":"wav"} Sep 02 22:21:45 volumio volumio[818]: verbose: CURRENT POSITION 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:45 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"Smile","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/两千年等一次.wav","trackType":"wav"} Sep 02 22:21:45 volumio volumio[818]: verbose: CURRENT POSITION 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:45 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:45 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:45 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 49ms Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 95ms Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 92ms Sep 02 22:21:45 volumio volumio[818]: info: ------------------------------ 90ms Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 79 58 00 00 f0 00 Sep 02 22:21:45 volumio kernel: critical medium error, dev sda, sector 45840728 op 0x0:(READ) flags 0x84700 phys_seg 2 prio class 2 Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 79 68 00 00 08 00 Sep 02 22:21:45 volumio kernel: critical medium error, dev sda, sector 45840744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:45 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 7c 68 00 00 80 00 Sep 02 22:21:45 volumio kernel: critical medium error, dev sda, sector 45841512 op 0x0:(READ) flags 0x80700 phys_seg 16 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 84 68 00 00 80 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45843560 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 84 70 00 00 08 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45843568 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 8e e8 00 00 20 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45846248 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 8e f8 00 00 08 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45846264 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 94 e8 00 00 20 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45847784 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 99 68 00 00 20 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45848936 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:46 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bb 99 80 00 00 08 00 Sep 02 22:21:46 volumio kernel: critical medium error, dev sda, sector 45848960 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:47 volumio volumio[818]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 02 22:21:47 volumio volumio[818]: info: Retrying Login Due to Network Error Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPlay Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::play index 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::serviceStop Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 1 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::serviceStop Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::stop Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand stop took 13 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::play index undefined Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::startPlaybackTimer Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::clearAddPlayTracks USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand stop took 2 milliseconds Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:48 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 8 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand clear took 7 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav" Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: No code Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: No code Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 70ms Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 70ms Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:48 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:48 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:48 volumio volumio[818]: error: updateQueue error: null Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:48 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 74ms Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand playlistinfo took 72 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav" took 71 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 5ms Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 5ms Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand play Sep 02 22:21:48 volumio volumio[818]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 85ms Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:48 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:48 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 31ms Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand play took 29 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 29ms Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 23ms Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 18 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 17 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 16 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 15 milliseconds Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: Sep 02 22:21:48 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 7 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand status took 6 milliseconds Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:48 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:48 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:48 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 66ms Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 120ms Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 115ms Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 114ms Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 02 22:21:48 volumio volumio[818]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:48 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:48 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":187,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:48 volumio volumio[818]: verbose: CURRENT POSITION 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:48 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:48 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:48 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 156ms Sep 02 22:21:48 volumio volumio[818]: info: ------------------------------ 156ms Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPlay Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::play index 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::updateTrackBlock Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrackBlock Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::serviceStop Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 6 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::serviceStop Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::stop Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand stop took 13 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::play index undefined Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::startPlaybackTimer Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::clearAddPlayTracks USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 2 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand stop took 2 milliseconds Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:50 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand clear took 5 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav" Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"???","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/拉德斯基进行曲.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: No code Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: No code Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: No code Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 129ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 125ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 124ms Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:50 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:50 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:50 volumio volumio[818]: error: updateQueue error: null Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:50 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 129ms Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand add "USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav" took 127 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 4ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 3ms Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand play Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:50 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces system playlist update Sep 02 22:21:50 volumio volumio[818]: info: Ignoring MPD Status Update Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 26ms Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand play took 24 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 21ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 19ms Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 13 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 11 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 10 milliseconds Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: Sep 02 22:21:50 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 7 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 5 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus stop Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:50 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:50 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 46ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 77ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 75ms Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 56 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 56 milliseconds Sep 02 22:21:50 volumio volumio[818]: info: sendMpdCommand playlistinfo took 56 milliseconds Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:50 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:50 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:50 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState stateService play Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus play Sep 02 22:21:50 volumio volumio[818]: info: Received an update from plugin. extracting info from payload Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:50 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:50 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 147ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 143ms Sep 02 22:21:50 volumio volumio[818]: info: ------------------------------ 143ms Sep 02 22:21:50 volumio kernel: scsi_io_completion_action: 20 callbacks suppressed Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf cc 80 00 00 80 00 Sep 02 22:21:50 volumio kernel: blk_print_req_error: 20 callbacks suppressed Sep 02 22:21:50 volumio kernel: critical medium error, dev sda, sector 46124160 op 0x0:(READ) flags 0x80700 phys_seg 16 prio class 2 Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:50 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf cc 80 00 00 08 00 Sep 02 22:21:50 volumio kernel: critical medium error, dev sda, sector 46124160 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf cc 88 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124168 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf cc 90 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124176 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf cc 98 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124184 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf ce 00 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124544 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf ce 08 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124552 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf ce 10 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124560 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf ce 18 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124568 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:21:51 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 02 bf cf 80 00 00 08 00 Sep 02 22:21:51 volumio kernel: critical medium error, dev sda, sector 46124928 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::volumioPause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::servicePause Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::servicePause Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::pause Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand pause Sep 02 22:21:55 volumio volumio[818]: info: Sep 02 22:21:55 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand pause took 3 milliseconds Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:55 volumio volumio[818]: info: Sep 02 22:21:55 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:55 volumio volumio[818]: info: Sep 02 22:21:55 volumio volumio[818]: ---------------------------- MPD announces state update: player Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::getState Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand status Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand status took 4 milliseconds Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand status took 3 milliseconds Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand status took 2 milliseconds Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::parseState Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 02 22:21:55 volumio volumio[818]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:55 volumio volumio[818]: verbose: ControllerMpd::parseTrackInfo Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5582,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:55 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::syncState stateService pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5582,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:55 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::syncState stateService pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:55 volumio volumio[818]: info: ControllerMpd::pushState Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::servicePushState Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5582,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"????","artist":"????","album":"T200A???","uri":"USB/5804-BE48/歌曲/1993-吻别[PolyGram]/加州旅馆.wav","trackType":"wav"} Sep 02 22:21:55 volumio volumio[818]: verbose: CURRENT POSITION 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::syncState stateService pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::syncState currentStatus pause Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::pushState Sep 02 22:21:55 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:21:55 volumio volumio[818]: info: CoreCommandRouter::volumioPushState Sep 02 22:21:55 volumio volumio[818]: info: CoreStateMachine::stPlaybackTimer Sep 02 22:21:55 volumio volumio[818]: info: ------------------------------ 58ms Sep 02 22:21:55 volumio volumio[818]: info: ------------------------------ 56ms Sep 02 22:21:55 volumio volumio[818]: info: ------------------------------ 55ms Sep 02 22:21:56 volumio volumio[818]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 22:21:56 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 22:21:57 volumio volumio[818]: info: MyVolumio login type: Token Sep 02 22:21:58 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 22:21:58 volumio volumio[818]: info: CURURI: albums:// Sep 02 22:21:58 volumio volumio[818]: info: listAlbums - loading Albums from cache Sep 02 22:21:58 volumio volumio[818]: info: Preload queue cleared Sep 02 22:21:58 volumio volumio[818]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/*/b16e4e2b-d26e-4641-876f-73ceb74f4943.jpg' Sep 02 22:22:00 volumio volumio[818]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jay%20Chou/Yeh%2C%20Hwei-Mei/60c577df-855b-477e-a5b8-2a4682a1dfb8.jpg' Sep 02 22:22:00 volumio volumio[818]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/LORIN%20MAAZEL/LORIN%20MAAZEL%20conducts%20RAVEL/415c4293-dbf4-40df-a34e-ec3930131f5d.jpg' Sep 02 22:22:00 volumio kernel: scsi_io_completion_action: 27 callbacks suppressed Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 07 33 2e 80 00 00 88 00 Sep 02 22:22:00 volumio kernel: blk_print_req_error: 27 callbacks suppressed Sep 02 22:22:00 volumio kernel: critical medium error, dev sda, sector 120794752 op 0x0:(READ) flags 0x80700 phys_seg 17 prio class 2 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 07 65 d2 48 00 00 08 00 Sep 02 22:22:00 volumio kernel: critical medium error, dev sda, sector 124113480 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 08 58 92 78 00 00 08 00 Sep 02 22:22:00 volumio kernel: critical medium error, dev sda, sector 140022392 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 08 58 92 78 00 00 08 00 Sep 02 22:22:00 volumio kernel: critical medium error, dev sda, sector 140022392 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Sep 02 22:22:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 07 33 2e d8 00 00 08 00 Sep 02 22:22:00 volumio kernel: critical medium error, dev sda, sector 120794840 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 02 22:22:02 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 22:22:02 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 22:22:02 volumio volumio[818]: info: Discovery: Getting this device information Sep 02 22:22:02 volumio volumio[818]: info: CoreCommandRouter::volumioGetState Sep 02 22:22:02 volumio volumio[818]: info: CorePlayQueue::getTrack 8 Sep 02 22:22:02 volumio volumio[818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 22:22:05 volumio volumio[818]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E7%8E%89%E5%88%9A/%E6%B0%91%E5%9B%BD%E6%97%A7%E6%A2%A6%EF%BC%88%E6%9D%8E%E7%8E%89%E5%88%9A%E5%88%AB%E6%A0%B7%E6%BC%94%E5%94%B1%20%E6%97%A7%E4%B8%8A%E6%B5%B7%E8%80%81%E6%83%85%E6%AD%8C%EF%BC%89/d0bde0df-9187-42a3-941b-d8b210b78118.jpg' Sep 02 22:22:05 volumio volumio[818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 22:22:05 volumio volumio[818]: Error: connect ETIMEDOUT 128.242.240.221:443 Sep 02 22:22:05 volumio volumio[818]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 02 22:22:05 volumio volumio[818]: errno: -110, Sep 02 22:22:05 volumio volumio[818]: code: 'ETIMEDOUT', Sep 02 22:22:05 volumio volumio[818]: syscall: 'connect', Sep 02 22:22:05 volumio volumio[818]: address: '128.242.240.221', Sep 02 22:22:05 volumio volumio[818]: port: 443 Sep 02 22:22:05 volumio volumio[818]: } Sep 02 22:22:05 volumio volumio[818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 22:22:05 volumio sudo[3757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 22:21 Sep 02 22:22:05 volumio sudo[3757]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"