-- Logs begin at Thu 2019-02-14 18:12:00 HKT, end at Sat 2024-10-26 14:57:25 HKT. -- Oct 26 14:56:05 volumio volumio[699]: info: Setting Geolocation for MyVolumio to as1 Oct 26 14:56:05 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:05 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:05 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:05 volumio volumio[699]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 14:56:06 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 14:56:06 volumio volumio[699]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Oct 26 14:56:06 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:06 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:06 volumio volumio[699]: info: Updating MyVolumio device info Oct 26 14:56:06 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:06 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:06 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:06 volumio volumio[699]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 14:56:09 volumio volumio[699]: info: MYVOLUMIO: Adding device Oct 26 14:56:09 volumio volumio[699]: info: MYVOLUMIO: Evaluating Server Oct 26 14:56:10 volumio volumio[699]: info: Setting Geolocation for MyVolumio to as1 Oct 26 14:56:10 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:10 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:10 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 14:56:11 volumio volumio[699]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 14:56:11 volumio volumio[699]: info: Updating MyVolumio device info Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:56:12 volumio volumio[699]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 14:56:17 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:17 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:18 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:18 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:18 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:18 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:18 volumio volumio[699]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.21 UA: Dart/3.4 (dart:io) Total Clients: 4 Oct 26 14:56:18 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:18 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:18 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 26 14:56:18 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 26 14:56:20 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 14:56:20 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:56:20 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Oct 26 14:56:20 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 14:56:20 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:56:20 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Oct 26 14:56:20 volumio volumio[699]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:20 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 14:56:20 volumio volumio[699]: info: Received Get System Info Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:20 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:20 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:20 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:20 volumio volumio[699]: info: Listing playlists Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 14:56:20 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 14:56:21 volumio sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 14:56:21 volumio sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:56:21 volumio sudo[1186]: pam_unix(sudo:session): session closed for user root Oct 26 14:56:21 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 14:56:21 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:56:21 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Oct 26 14:56:21 volumio volumio[699]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:21 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 14:56:21 volumio volumio[699]: info: Received Get System Info Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:21 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:21 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:21 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:21 volumio volumio[699]: info: Listing playlists Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 14:56:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:22 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 14:56:22 volumio volumio[699]: info: Received Get System Info Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:22 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:22 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:22 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 26 14:56:25 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 14:56:25 volumio volumio[699]: info: Received Get System Info Oct 26 14:56:25 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:25 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:25 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:25 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:25 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:25 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:25 volumio volumio[699]: info: CoreCommandRouter::volumioGetQueue Oct 26 14:56:25 volumio volumio[699]: info: CoreStateMachine::getQueue Oct 26 14:56:25 volumio volumio[699]: info: CorePlayQueue::getQueue Oct 26 14:56:27 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 14:56:27 volumio volumio[699]: info: Received Get System Info Oct 26 14:56:27 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:27 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:27 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:27 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:27 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:27 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:36 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 14:56:36 volumio volumio[699]: info: CURURI: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫 Oct 26 14:56:36 volumio volumio[699]: info: Preload queue cleared Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/02 祇願一生愛一人.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/03 一生不變.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/04 我只在乎你.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/05 夢仍是一樣.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/06 給您留念.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/07 無名份的浪漫.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/08 也曾相識.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/09 我愛你.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/10 這是我心裡對白.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/11 印象.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/12 儍女.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/13 明天你是否依然愛我.wav Oct 26 14:56:36 volumio volumio[699]: info: Preloading song: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/14 月半彎.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F01%20%E5%A4%B1%E6%88%80.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/02 祇願一生愛一人.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F02%20%E7%A5%87%E9%A1%98%E4%B8%80%E7%94%9F%E6%84%9B%E4%B8%80%E4%BA%BA.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/02 祇願一生愛一人.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/03 一生不變.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F03%20%E4%B8%80%E7%94%9F%E4%B8%8D%E8%AE%8A.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/03 一生不變.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/04 我只在乎你.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F04%20%E6%88%91%E5%8F%AA%E5%9C%A8%E4%B9%8E%E4%BD%A0.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/04 我只在乎你.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/05 夢仍是一樣.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F05%20%E5%A4%A2%E4%BB%8D%E6%98%AF%E4%B8%80%E6%A8%A3.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/05 夢仍是一樣.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/06 給您留念.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F06%20%E7%B5%A6%E6%82%A8%E7%95%99%E5%BF%B5.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/06 給您留念.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/07 無名份的浪漫.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F07%20%E7%84%A1%E5%90%8D%E4%BB%BD%E7%9A%84%E6%B5%AA%E6%BC%AB.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/07 無名份的浪漫.wav Oct 26 14:56:36 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/08 也曾相識.wav in service mpd Oct 26 14:56:36 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F08%20%E4%B9%9F%E6%9B%BE%E7%9B%B8%E8%AD%98.wav&metadata=false Oct 26 14:56:36 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/08 也曾相識.wav Oct 26 14:56:37 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/09 我愛你.wav in service mpd Oct 26 14:56:37 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F09%20%E6%88%91%E6%84%9B%E4%BD%A0.wav&metadata=false Oct 26 14:56:37 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/09 我愛你.wav Oct 26 14:56:37 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/10 這是我心裡對白.wav in service mpd Oct 26 14:56:37 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F10%20%E9%80%99%E6%98%AF%E6%88%91%E5%BF%83%E8%A3%A1%E5%B0%8D%E7%99%BD.wav&metadata=false Oct 26 14:56:37 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/10 這是我心裡對白.wav Oct 26 14:56:37 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/11 印象.wav in service mpd Oct 26 14:56:37 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F11%20%E5%8D%B0%E8%B1%A1.wav&metadata=false Oct 26 14:56:37 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/11 印象.wav Oct 26 14:56:37 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/12 儍女.wav in service mpd Oct 26 14:56:37 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F12%20%E5%84%8D%E5%A5%B3.wav&metadata=false Oct 26 14:56:37 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/12 儍女.wav Oct 26 14:56:37 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/13 明天你是否依然愛我.wav in service mpd Oct 26 14:56:37 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F13%20%E6%98%8E%E5%A4%A9%E4%BD%A0%E6%98%AF%E5%90%A6%E4%BE%9D%E7%84%B6%E6%84%9B%E6%88%91.wav&metadata=false Oct 26 14:56:37 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/13 明天你是否依然愛我.wav Oct 26 14:56:37 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/14 月半彎.wav in service mpd Oct 26 14:56:37 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F%3F/%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2F%E5%AF%B6%E9%BA%97%E9%87%91%E6%BF%83%E6%83%85%E6%B5%AA%E6%BC%AB%2F14%20%E6%9C%88%E5%8D%8A%E5%BD%8E.wav&metadata=false Oct 26 14:56:37 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/14 月半彎.wav Oct 26 14:56:37 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:37 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:37 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:37 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:37 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:37 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:39 volumio volumio[909]: .......................................................................................................................................................................................................................................................................................................................................++++ Oct 26 14:56:39 volumio volumio[909]: e is 65537 (0x010001) Oct 26 14:56:39 volumio volumio[909]: writing RSA key Oct 26 14:56:39 volumio volumio[699]: info: Preload queue cleared Oct 26 14:56:39 volumio volumio[699]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::ClearQueue Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::stop Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::clearPlayQueue Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::saveQueue Oct 26 14:56:39 volumio volumio[699]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::addQueueItems Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::addQueueItems Oct 26 14:56:39 volumio volumio[699]: info: Preload queue cleared Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/02 祇願一生愛一人.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/02 祇願一生愛一人.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/03 一生不變.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/03 一生不變.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/04 我只在乎你.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/04 我只在乎你.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/05 夢仍是一樣.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/05 夢仍是一樣.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/06 給您留念.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/06 給您留念.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/07 無名份的浪漫.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/07 無名份的浪漫.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/08 也曾相識.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/08 也曾相識.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/09 我愛你.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/09 我愛你.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/10 這是我心裡對白.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/10 這是我心裡對白.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/11 印象.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/11 印象.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/12 儍女.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/12 儍女.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/13 明天你是否依然愛我.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/13 明天你是否依然愛我.wav Oct 26 14:56:39 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/14 月半彎.wav Oct 26 14:56:39 volumio volumio[699]: info: Using cached record of: music-library/NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/14 月半彎.wav Oct 26 14:56:39 volumio volumio[699]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::saveQueue Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::updateTrackBlock Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::getTrackBlock Oct 26 14:56:39 volumio volumio[699]: info: CoreCommandRouter::volumioPlay Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::play index 0 Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::stop Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::play index undefined Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:39 volumio volumio[699]: info: CoreStateMachine::startPlaybackTimer Oct 26 14:56:39 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:39 volumio volumio[699]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav Oct 26 14:56:39 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 14:56:39 volumio volumio[699]: info: sendMpdCommand stop took 9 milliseconds Oct 26 14:56:39 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 14:56:39 volumio volumio[699]: info: Oct 26 14:56:39 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:39 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:39 volumio volumio[699]: info: sendMpdCommand clear took 9 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav" Oct 26 14:56:40 volumio volumio[699]: error: updateQueue error: null Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:40 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 12ms Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand add "NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav" took 7 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand play Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:40 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:40 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:40 volumio volumio[699]: error: Upnp client error: Error: This socket has been ended by the other party Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 23ms Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand play took 19 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 17ms Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 15ms Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:40 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:40 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 13ms Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 6ms Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand status took 27 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand status took 24 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand status took 23 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:40 volumio volumio[699]: info: Oct 26 14:56:40 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand status took 56 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand clearerror took 43 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand clearerror took 41 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand clearerror took 38 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand status took 19 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand status took 17 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"??","album":"???????","uri":"NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav","trackType":"wav"} Oct 26 14:56:40 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"??","album":"???????","uri":"NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav","trackType":"wav"} Oct 26 14:56:40 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"??","album":"???????","uri":"NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav","trackType":"wav"} Oct 26 14:56:40 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 155ms Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 152ms Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 148ms Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand clearerror took 115 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand playlistinfo took 108 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 26 14:56:40 volumio volumio[699]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:40 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"??","album":"???????","uri":"NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav","trackType":"wav"} Oct 26 14:56:40 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"??","album":"???????","uri":"NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav","trackType":"wav"} Oct 26 14:56:40 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:40 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"??","album":"???????","uri":"NAS/MusicFiles/CD Music/Various Artists/寶麗金濃情浪漫/01 失戀.wav","trackType":"wav"} Oct 26 14:56:40 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:40 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 231ms Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 192ms Oct 26 14:56:40 volumio volumio[699]: info: ------------------------------ 191ms Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 14:56:40 volumio volumio[699]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 26 14:56:40 volumio volumio[699]: info: Received Get System Version Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 14:56:40 volumio volumio[699]: info: Received Get System Info Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:56:40 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:56:40 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:40 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:56:46 volumio ntpd[656]: Soliciting pool server 2001:ac8:a:23:0:1:0:26 Oct 26 14:56:49 volumio volumio[699]: info: Preload queue cleared Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::ClearQueue Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::clearPlayQueue Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::saveQueue Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::addQueueItems Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::addQueueItems Oct 26 14:56:49 volumio volumio[699]: info: Preload queue cleared Oct 26 14:56:49 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test Oct 26 14:56:49 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test in service mpd Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F01%20Trittico_Allegro%20Waestoso.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F02%20Dies%20Irae.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/02 Dies Irae.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F03%20Etuds%20Op.%2010-12in%20Cminor%20Revolutionary.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/03 Etuds Op. 10-12in Cminor Revolutionary.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F04%20Lindenkiirche%20Berlin.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/04 Lindenkiirche Berlin.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F05%20Enter%20Act.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/05 Enter Act.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F06%20Cello%20Suite%20No.1.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/06 Cello Suite No.1.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F07%20The%20Royal%20March.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/07 The Royal March.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F08%20Tarantella.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/08 Tarantella.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F09%20Watching%20the%20Wheat.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/09 Watching the Wheat.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F10%20Sonata%20Concertata.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/10 Sonata Concertata.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F11%20March%20Turkish.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/11 March Turkish.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F12%20Tempus%20est%20iocundum.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/12 Tempus est iocundum.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F13%20Banjo%20And%20Fiddle.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/13 Banjo And Fiddle.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F14%20Sonata%20NO.1%20Gmajor.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/14 Sonata NO.1 Gmajor.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F15%20El%20Choclo.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/15 El Choclo.wav Oct 26 14:56:49 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=%3F%3F/%3F%3F%3F%3F%3F%3F%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2FCD%20Music%2FVarious%20Artists%2FPotions%20-%20Classical%20Power%20Test%2F16%20Symphony%20No.5%20ln%20C%20Minor%2COp.67-1.Allegro%20con%20drio.wav&metadata=false Oct 26 14:56:49 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/16 Symphony No.5 ln C Minor,Op.67-1.Allegro con drio.wav Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::saveQueue Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::updateTrackBlock Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrackBlock Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPlay Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::play index 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::play index undefined Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::startPlaybackTimer Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand stop took 4 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:49 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 8 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand clear took 8 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 5 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 4 milliseconds Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav" Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: 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} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: No code Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: 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} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: No code Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 40ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 39ms Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:49 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:49 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:49 volumio volumio[699]: error: updateQueue error: null Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:49 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 75ms Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 73 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand add "NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav" took 73 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 8ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 6ms Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand play Oct 26 14:56:49 volumio volumio[699]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 89ms Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:49 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:49 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 22ms Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand play took 19 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 16ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 15ms Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 17 milliseconds Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 14 milliseconds Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 13 milliseconds Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: info: Oct 26 14:56:49 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 45 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand clearerror took 39 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand clearerror took 38 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand clearerror took 37 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 12 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand status took 11 milliseconds Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 106ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 102ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 99ms Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand clearerror took 72 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 26 14:56:49 volumio volumio[699]: info: sendMpdCommand playlistinfo took 66 milliseconds Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:49 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:49 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:49 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:49 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 159ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 125ms Oct 26 14:56:49 volumio volumio[699]: info: ------------------------------ 122ms Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPlay Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::play index undefined Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::startPlaybackTimer Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand stop took 1 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:56 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand clear took 3 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav" Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: error: updateQueue error: null Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:56 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 7 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 8ms Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand add "NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav" took 6 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 4 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 4 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand play Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: 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} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: No code Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: 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} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: No code Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: 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} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: No code Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 113ms Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 111ms Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 111ms Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:56 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces system playlist update Oct 26 14:56:56 volumio volumio[699]: info: Ignoring MPD Status Update Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 115ms Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand play took 112 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 7ms Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 5ms Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: info: Oct 26 14:56:56 volumio volumio[699]: ---------------------------- MPD announces state update: player Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::getState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 39 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 41 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 40 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand status took 40 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseState Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand clearerror took 20 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand clearerror took 18 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand clearerror took 17 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand clearerror took 16 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 26 14:56:56 volumio volumio[699]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:56 volumio volumio[699]: verbose: ControllerMpd::parseTrackInfo Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ControllerMpd::pushState Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::servicePushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trittico_Allegro Waestoso","artist":"??","album":"?????????","uri":"NAS/MusicFiles/CD Music/Various Artists/Potions - Classical Power Test/01 Trittico_Allegro Waestoso.wav","trackType":"wav"} Oct 26 14:56:56 volumio volumio[699]: verbose: CURRENT POSITION 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState stateService pause Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::syncState currentStatus stop Oct 26 14:56:56 volumio volumio[699]: info: CoreStateMachine::pushState Oct 26 14:56:56 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:56:56 volumio volumio[699]: info: CoreCommandRouter::volumioPushState Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 106ms Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 106ms Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 103ms Oct 26 14:56:56 volumio volumio[699]: info: ------------------------------ 103ms Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:57:04 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:04 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:57:04 volumio volumio[699]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.21 UA: Dart/3.4 (dart:io) Total Clients: 4 Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:04 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 26 14:57:04 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 26 14:57:05 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:57:05 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:57:05 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:57:05 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:05 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:05 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:57:06 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 14:57:06 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:57:06 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Oct 26 14:57:06 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 14:57:06 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:57:06 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Oct 26 14:57:06 volumio volumio[699]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Oct 26 14:57:07 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 14:57:07 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:57:07 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Oct 26 14:57:07 volumio sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 14:57:07 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:57:07 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root Oct 26 14:57:07 volumio volumio[699]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 6 Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:07 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::volumioGetQueue Oct 26 14:57:07 volumio volumio[699]: info: CoreStateMachine::getQueue Oct 26 14:57:07 volumio volumio[699]: info: CorePlayQueue::getQueue Oct 26 14:57:07 volumio volumio[699]: info: Listing playlists Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 14:57:07 volumio volumio[699]: info: Received Get System Info Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:57:07 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:07 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:07 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 14:57:07 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 26 14:57:09 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 14:57:09 volumio volumio[699]: info: Received Get System Info Oct 26 14:57:09 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:57:09 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:57:09 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:57:09 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:09 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:09 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:57:09 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 26 14:57:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 14:57:11 volumio volumio[699]: info: Received Get System Info Oct 26 14:57:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:57:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:57:11 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:57:11 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:11 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:11 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:57:12 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 14:57:12 volumio volumio[699]: info: Received Get System Info Oct 26 14:57:12 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 14:57:12 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 14:57:12 volumio volumio[699]: info: Discovery: Getting this device information Oct 26 14:57:12 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Oct 26 14:57:12 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Oct 26 14:57:12 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 14:57:17 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 14:57:17 volumio volumio[699]: info: CURURI: music-library/NAS/MusicFiles/50TB Oct 26 14:57:18 volumio volumio[699]: info: Preload queue cleared Oct 26 14:57:21 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 14:57:21 volumio volumio[699]: info: CURURI: music-library/NAS/MusicFiles/50TB/古典音乐（2） Oct 26 14:57:22 volumio volumio[699]: info: Preload queue cleared Oct 26 14:57:24 volumio volumio[699]: info: Preload queue cleared Oct 26 14:57:24 volumio volumio[699]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 14:57:24 volumio volumio[699]: info: CoreStateMachine::ClearQueue Oct 26 14:57:24 volumio volumio[699]: info: CoreStateMachine::stop Oct 26 14:57:24 volumio volumio[699]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 14:57:24 volumio volumio[699]: info: CorePlayQueue::clearPlayQueue Oct 26 14:57:24 volumio volumio[699]: info: CorePlayQueue::saveQueue Oct 26 14:57:24 volumio volumio[699]: info: CoreCommandRouter::volumioPushQueue Oct 26 14:57:24 volumio volumio[699]: info: CoreStateMachine::addQueueItems Oct 26 14:57:24 volumio volumio[699]: info: CorePlayQueue::addQueueItems Oct 26 14:57:24 volumio volumio[699]: info: Preload queue cleared Oct 26 14:57:24 volumio volumio[699]: info: Adding Item to queue: music-library/NAS/MusicFiles/50TB/古典音乐（2）/[古典] - BBC Legends Collection(BBC 传奇系列收藏) Oct 26 14:57:24 volumio volumio[699]: info: Exploding uri music-library/NAS/MusicFiles/50TB/古典音乐（2）/[古典] - BBC Legends Collection(BBC 传奇系列收藏) in service mpd Oct 26 14:57:25 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=Jascha%20Horenstein/BBC%20Legends%2040012%3A%20Jascha%20Horenstein/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2F50TB%2F%E5%8F%A4%E5%85%B8%E9%9F%B3%E4%B9%90%EF%BC%882%EF%BC%89%2F%5B%E5%8F%A4%E5%85%B8%5D%20-%20BBC%20Legends%20Collection(BBC%20%E4%BC%A0%E5%A5%87%E7%B3%BB%E5%88%97%E6%94%B6%E8%97%8F)%2FBBC%2040012%20-%20Mahler%20Sym%208_J.%20Horenstein%20CD1.ape&metadata=false Oct 26 14:57:25 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/50TB/古典音乐（2）/[古典] - BBC Legends Collection(BBC 传奇系列收藏)/BBC 40012 - Mahler Sym 8_J. Horenstein CD1.ape Oct 26 14:57:25 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=Jascha%20Horenstein/Mahler%3A%20Sym%208%20%5BHorenstein%5D%2C%20Disc%201/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2F50TB%2F%E5%8F%A4%E5%85%B8%E9%9F%B3%E4%B9%90%EF%BC%882%EF%BC%89%2F%5B%E5%8F%A4%E5%85%B8%5D%20-%20BBC%20Legends%20Collection(BBC%20%E4%BC%A0%E5%A5%87%E7%B3%BB%E5%88%97%E6%94%B6%E8%97%8F)%2FBBC%2040012%20-%20Mahler%20Sym%208_J.%20Horenstein%20CD1.cue&metadata=false Oct 26 14:57:25 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/50TB/古典音乐（2）/[古典] - BBC Legends Collection(BBC 传奇系列收藏)/BBC 40012 - Mahler Sym 8_J. Horenstein CD1.cue Oct 26 14:57:25 volumio volumio[699]: info: ALBUMART /albumart?cacheid=876&web=Jascha%20Horenstein/Mahler%3A%20Sym%208%20%5BHorenstein%5D%2C%20Disc%201/extralarge&path=%2Fmnt%2FNAS%2FMusicFiles%2F50TB%2F%E5%8F%A4%E5%85%B8%E9%9F%B3%E4%B9%90%EF%BC%882%EF%BC%89%2F%5B%E5%8F%A4%E5%85%B8%5D%20-%20BBC%20Legends%20Collection(BBC%20%E4%BC%A0%E5%A5%87%E7%B3%BB%E5%88%97%E6%94%B6%E8%97%8F)%2FBBC%2040012%20-%20Mahler%20Sym%208_J.%20Horenstein%20CD1.cue&metadata=false Oct 26 14:57:25 volumio volumio[699]: info: URI /mnt/NAS/MusicFiles/50TB/古典音乐（2）/[古典] - BBC Legends Collection(BBC 传奇系列收藏)/BBC 40012 - Mahler Sym 8_J. Horenstein CD1.cue Oct 26 14:57:25 volumio volumio[699]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 14:57:25 volumio volumio[699]: Error: Unable to resolve or reject the same promise twice Oct 26 14:57:25 volumio volumio[699]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 26 14:57:25 volumio volumio[699]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 26 14:57:25 volumio volumio[699]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 26 14:57:25 volumio volumio[699]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 26 14:57:25 volumio volumio[699]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 26 14:57:25 volumio volumio[699]: at Socket.emit (events.js:315:20) Oct 26 14:57:25 volumio volumio[699]: at addChunk (internal/streams/readable.js:309:12) Oct 26 14:57:25 volumio volumio[699]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 26 14:57:25 volumio volumio[699]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 26 14:57:25 volumio volumio[699]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 26 14:57:25 volumio volumio[699]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 14:57:25 volumio sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 14:56 Oct 26 14:57:25 volumio sudo[1290]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"