-- Logs begin at Sat 2025-07-12 10:44:34 CST, end at Sat 2025-07-12 11:06:55 CST. -- Jul 12 11:05:01 volumio-x86-784 volumio[6197]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 12 11:05:01 volumio-x86-784 volumio[6197]: info: Retrying Login Due to Network Error Jul 12 11:05:08 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 12 11:05:08 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:11 volumio-x86-784 volumio[6197]: info: MyVolumio login type: Token Jul 12 11:05:13 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:13 volumio-x86-784 volumio[6197]: info: CURURI: artists:// Jul 12 11:05:14 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:14 volumio-x86-784 volumio[6197]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyond/4ffee3c0-d48a-467f-aac4-577ad6e1e1a8.jpg' Jul 12 11:05:18 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:18 volumio-x86-784 volumio[6197]: info: CURURI: playlists Jul 12 11:05:18 volumio-x86-784 volumio[6197]: info: Listing playlists Jul 12 11:05:18 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to 192.168.3.222 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 12 11:05:18 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:20 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to 192.168.3.222 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 12 11:05:20 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:20 volumio-x86-784 volumio[6197]: info: CURURI: playlists Jul 12 11:05:20 volumio-x86-784 volumio[6197]: info: Listing playlists Jul 12 11:05:20 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to 192.168.3.222 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 12 11:05:20 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:21 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to 192.168.3.222 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jul 12 11:05:21 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:21 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 nmbd[1119]: [2025/07/12 11:05:22.871124, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 12 11:05:22 volumio-x86-784 nmbd[1119]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.222 for name WORKGROUP<1d>. Jul 12 11:05:22 volumio-x86-784 nmbd[1119]: This response was from IP 192.168.3.149, reporting an IP address of 192.168.3.149. Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: Received Get System Info Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: Discovery: Getting this device information Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: Received Get System Info Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: Discovery: Getting this device information Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: Listing playlists Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetQueue Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CoreStateMachine::getQueue Jul 12 11:05:22 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getQueue Jul 12 11:05:23 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:26 volumio-x86-784 volumio[6197]: info: CURURI: music-library Jul 12 11:05:26 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:34 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:34 volumio-x86-784 volumio[6197]: info: CURURI: music-library/USB Jul 12 11:05:34 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:36 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:36 volumio-x86-784 volumio[6197]: info: CURURI: music-library/USB/Lexar Jul 12 11:05:36 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: CURURI: music-library/USB/Lexar/DFS Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/08 - 渡口.dff Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/11 散了吧 林志炫 ONE take Disc 3.dff Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/范晓萱-眼泪.wav Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/范晓萱-雪人.wav Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/鬼迷心窍.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/01 - Various Artist - Track 01.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/梦醒时分.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/伤痕.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/领悟.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/现代爱情故事.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/03 难得有情人 - 关淑怡 .dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/谁明浪子心.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/你是我胸口永远的痛.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Preloading song: music-library/USB/Lexar/DFS/01 - 张学友&汤宝如 - 相思风雨中.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/08 - 渡口.dff in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=//extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F08%20-%20%E6%B8%A1%E5%8F%A3.dff&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/08 - 渡口.dff Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/11 散了吧 林志炫 ONE take Disc 3.dff in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=//extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F11%20%E6%95%A3%E4%BA%86%E5%90%A7%20%E6%9E%97%E5%BF%97%E7%82%AB%20ONE%20take%20Disc%203.dff&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/11 散了吧 林志炫 ONE take Disc 3.dff Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/范晓萱-眼泪.wav in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=//extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E8%8C%83%E6%99%93%E8%90%B1-%E7%9C%BC%E6%B3%AA.wav&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/范晓萱-眼泪.wav Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/范晓萱-雪人.wav in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=//extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E8%8C%83%E6%99%93%E8%90%B1-%E9%9B%AA%E4%BA%BA.wav&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/范晓萱-雪人.wav Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/鬼迷心窍.dsf in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Jonathan%20Lee/Jonathan%20Lee%20Collection%201/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E9%AC%BC%E8%BF%B7%E5%BF%83%E7%AA%8D.dsf&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/鬼迷心窍.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/01 - Various Artist - Track 01.dsf in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Various%20Artist/Jonthan%20Lee%20Collection%202/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F01%20-%20Various%20Artist%20-%20Track%2001.dsf&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/01 - Various Artist - Track 01.dsf Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/梦醒时分.dsf in service mpd Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Various%20Artist/Jonthan%20Lee%20Collection%202/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E6%A2%A6%E9%86%92%E6%97%B6%E5%88%86.dsf&metadata=false Jul 12 11:05:37 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/梦醒时分.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/伤痕.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/领悟.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/现代爱情故事.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/03 难得有情人 - 关淑怡 .dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/谁明浪子心.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/你是我胸口永远的痛.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: Exploding uri music-library/USB/Lexar/DFS/01 - 张学友&汤宝如 - 相思风雨中.dsf in service mpd Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Various%20Artist/Jonthan%20Lee%20Collection%202/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E4%BC%A4%E7%97%95.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/伤痕.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Various%20Artist/Jonthan%20Lee%20Collection%202/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E9%A2%86%E6%82%9F.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/领悟.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=SACD%20%3A%20All%20Stars%20Greatest%20Hits/SACD%20%3A%20All%20Stars%20Greatest%20Hits/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E7%8E%B0%E4%BB%A3%E7%88%B1%E6%83%85%E6%95%85%E4%BA%8B.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/现代爱情故事.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Various%20Artists%2C%20Unlimited%20Four/Sweet%20Soul%20Baby%203-%20Rare%20and%20Hard%20to%20Find%20Soul%2045s/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F03%20%E9%9A%BE%E5%BE%97%E6%9C%89%E6%83%85%E4%BA%BA%20-%20%E5%85%B3%E6%B7%91%E6%80%A1%C2%A0.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/03 难得有情人 - 关淑怡 .dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Wang%20Chieh/WANG%20CHIEH%20-%20SACD%20COLLECTION/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E8%B0%81%E6%98%8E%E6%B5%AA%E5%AD%90%E5%BF%83.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/谁明浪子心.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=Wang%20Chieh/WANG%20CHIEH%20-%20SACD%20COLLECTION/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F%E4%BD%A0%E6%98%AF%E6%88%91%E8%83%B8%E5%8F%A3%E6%B0%B8%E8%BF%9C%E7%9A%84%E7%97%9B.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/你是我胸口永远的痛.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=%E5%8A%89%E5%BE%B7%E8%8F%AF/%E5%8A%89%E5%BE%B7%E8%8F%AF%20%E4%B8%AD%E5%9C%8B%E4%BA%BA%20SACD%E7%B2%BE%E9%81%B8/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F02%20%E5%8A%89%E5%BE%B7%E8%8F%AF%20-%20%E5%AD%A4%E6%98%9F%E6%B7%9A.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=%E5%8A%89%E5%BE%B7%E8%8F%AF/%E5%8A%89%E5%BE%B7%E8%8F%AF%20%E4%B8%AD%E5%9C%8B%E4%BA%BA%20SACD%E7%B2%BE%E9%81%B8/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F08%20%E5%8A%89%E5%BE%B7%E8%8F%AF%20-%20%E7%94%B7%E4%BA%BA%E5%93%AD%E5%90%A7%E4%B8%8D%E6%98%AF%E7%BD%AA.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: ALBUMART /albumart?cacheid=946&web=%E5%BC%A0%E5%AD%A6%E5%8F%8B%2C%20%E6%B1%A4%E5%AE%9D%E5%A6%82/%E7%9C%9F%E6%83%85%E6%B5%81%E9%9C%B2/extralarge&path=%2Fmnt%2FUSB%2FLexar%2FDFS%2F01%20-%20%E5%BC%A0%E5%AD%A6%E5%8F%8B%26%E6%B1%A4%E5%AE%9D%E5%A6%82%20-%20%E7%9B%B8%E6%80%9D%E9%A3%8E%E9%9B%A8%E4%B8%AD.dsf&metadata=false Jul 12 11:05:38 volumio-x86-784 volumio[6197]: info: URI /mnt/USB/Lexar/DFS/01 - 张学友&汤宝如 - 相思风雨中.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::ClearQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stPlaybackTimer Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::serviceStop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::serviceStop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::clearPlayQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::saveQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::addQueueItems Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::addQueueItems Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/08 - 渡口.dff Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/08 - 渡口.dff Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::saveQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPlay Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::play index 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::addQueueItems Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::addQueueItems Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Preload queue cleared Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/11 散了吧 林志炫 ONE take Disc 3.dff Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/11 散了吧 林志炫 ONE take Disc 3.dff Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/范晓萱-眼泪.wav Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/范晓萱-眼泪.wav Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/范晓萱-雪人.wav Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/范晓萱-雪人.wav Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/鬼迷心窍.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/鬼迷心窍.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/01 - Various Artist - Track 01.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/01 - Various Artist - Track 01.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/梦醒时分.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/梦醒时分.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/伤痕.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/伤痕.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/领悟.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/领悟.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/现代爱情故事.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/现代爱情故事.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/03 难得有情人 - 关淑怡 .dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/03 难得有情人 - 关淑怡 .dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/谁明浪子心.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/谁明浪子心.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/你是我胸口永远的痛.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/你是我胸口永远的痛.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Adding Item to queue: music-library/USB/Lexar/DFS/01 - 张学友&汤宝如 - 相思风雨中.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Using cached record of: music-library/USB/Lexar/DFS/01 - 张学友&汤宝如 - 相思风雨中.dsf Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::saveQueue Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::play index undefined Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::startPlaybackTimer Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::clearAddPlayTracks USB/Lexar/DFS/08 - 渡口.dff Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand stop took 112 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand stop took 45 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 11 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand clear took 10 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 10 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 8 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand add "USB/Lexar/DFS/08 - 渡口.dff" Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: 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} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: 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} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 106ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 105ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: error: updateQueue error: null Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 114ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 109 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand add "USB/Lexar/DFS/08 - 渡口.dff" took 110 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 14ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 13ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 135ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 43ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand play took 34 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 33ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 30ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 12 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 10 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 8 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Jul 12 11:05:39 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 42 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 37 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 35 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - 渡口.dff","artist":null,"album":null,"uri":"USB/Lexar/DFS/08 - 渡口.dff","trackType":"dff"} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - 渡口.dff","artist":null,"album":null,"uri":"USB/Lexar/DFS/08 - 渡口.dff","trackType":"dff"} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 sudo[5888]: pam_unix(sudo:session): session closed for user root Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - 渡口.dff","artist":null,"album":null,"uri":"USB/Lexar/DFS/08 - 渡口.dff","trackType":"dff"} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 98ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 182ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 181ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 139 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 137 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 136 milliseconds Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - 渡口.dff","artist":null,"album":null,"uri":"USB/Lexar/DFS/08 - 渡口.dff","trackType":"dff"} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - 渡口.dff","artist":null,"album":null,"uri":"USB/Lexar/DFS/08 - 渡口.dff","trackType":"dff"} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - 渡口.dff","artist":null,"album":null,"uri":"USB/Lexar/DFS/08 - 渡口.dff","trackType":"dff"} Jul 12 11:05:39 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 321ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 314ms Jul 12 11:05:39 volumio-x86-784 volumio[6197]: info: ------------------------------ 313ms Jul 12 11:05:41 volumio-x86-784 volumio[6197]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 12 11:05:41 volumio-x86-784 volumio[6197]: info: Retrying Login Due to Network Error Jul 12 11:05:48 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:48 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:48 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:48 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:48 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: VolumeController::SetAlsaVolume100 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:05:49 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:05:50 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:50 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:51 volumio-x86-784 volumio[6197]: info: MyVolumio login type: Token Jul 12 11:05:52 volumio-x86-784 sudo[6781]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jul 12 11:05:52 volumio-x86-784 sudo[6781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 11:05:57 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jul 12 11:05:57 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:57 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:05:58 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jul 12 11:05:58 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:05:58 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioNext Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::next Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stPlaybackTimer Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::serviceStop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 0 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::serviceStop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand stop took 26 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::play index undefined Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::startPlaybackTimer Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::clearAddPlayTracks USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand stop took 3 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 6 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand clear took 6 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 5 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 3 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand add "USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf" Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: 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} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: 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} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 91ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 90ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: error: updateQueue error: null Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 98ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 96 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand add "USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf" took 96 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 10ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 9ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 109ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 26ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand play took 21 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 20ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 18ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 11 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 10 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 9 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:10 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 31 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 22 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 17 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 79ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 128ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 128ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 103 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:10 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 266ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 260ms Jul 12 11:06:10 volumio-x86-784 volumio[6197]: info: ------------------------------ 256ms Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioSeek Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::seek Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: TRACKBLOCK {"uri":"mnt/USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","service":"mpd","name":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=946&web=%E5%8A%89%E5%BE%B7%E8%8F%AF/%E5%8A%89%E5%BE%B7%E8%8F%AF%20%E4%B8%AD%E5%9C%8B%E4%BA%BA%20SACD%E7%B2%BE%E9%81%B8/extralarge&path=%2FUSB%2FLexar%2FDFS&metadata=false","duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::startPlaybackTimer Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::seek Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: error: null Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:14 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:14 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:14 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 21 milliseconds Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 20 milliseconds Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 17 milliseconds Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89000,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89000,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89000,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:14 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ------------------------------ 151ms Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ------------------------------ 150ms Jul 12 11:06:14 volumio-x86-784 volumio[6197]: info: ------------------------------ 148ms Jul 12 11:06:16 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to 192.168.3.222 from 192.168.3.36 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: Received Get System Info Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: Discovery: Getting this device information Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: Received Get System Info Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: Discovery: Getting this device information Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: Listing playlists Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetQueue Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CoreStateMachine::getQueue Jul 12 11:06:16 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getQueue Jul 12 11:06:21 volumio-x86-784 volumio[6197]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 12 11:06:21 volumio-x86-784 volumio[6197]: info: Retrying Login Due to Network Error Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioNext Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::next Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stop Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::stPlaybackTimer Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::serviceStop Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 14 Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::serviceStop Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: ControllerMpd::stop Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:25 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: sendMpdCommand stop took 99 milliseconds Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::play index undefined Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::startPlaybackTimer Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::clearAddPlayTracks USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CoreStateMachine::updateTrackBlock Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrackBlock Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 4 milliseconds Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: sendMpdCommand stop took 3 milliseconds Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:25 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:25 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:25 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:25 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand clear took 6 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 5 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 3 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand add "USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf" Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 劉德華 - 男人哭吧不是罪","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/08 劉德華 - 男人哭吧不是罪.dsf","trackType":"dsf"} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: 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} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: 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} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: No code Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 142ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 134ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 134ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: error: updateQueue error: null Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 142ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand add "USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf" took 139 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 10ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 8ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces system playlist update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Ignoring MPD Status Update Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 74ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand play took 71 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 70ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 68ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 56 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 53 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Jul 12 11:06:26 volumio-x86-784 volumio[6197]: ---------------------------- MPD announces state update: player Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::getState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand status Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 114 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 89 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 86 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 86 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 18 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":339,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 劉德華 - 孤星淚","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf","trackType":"dsf"} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus stop Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":339,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 劉德華 - 孤星淚","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf","trackType":"dsf"} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 199ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 214ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand status took 83 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 73 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 74 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 69 milliseconds Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":339,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 劉德華 - 孤星淚","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf","trackType":"dsf"} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":339,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 劉德華 - 孤星淚","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf","trackType":"dsf"} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":339,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 劉德華 - 孤星淚","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf","trackType":"dsf"} Jul 12 11:06:26 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 344ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 324ms Jul 12 11:06:26 volumio-x86-784 volumio[6197]: info: ------------------------------ 243ms Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: sendMpdCommand playlistinfo took 1417 milliseconds Jul 12 11:06:27 volumio-x86-784 volumio[6197]: verbose: ControllerMpd::parseTrackInfo Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: ControllerMpd::pushState Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::servicePushState Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:27 volumio-x86-784 volumio[6197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":79,"duration":339,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 劉德華 - 孤星淚","artist":"劉德華","album":"劉德華 中國人 SACD精選","uri":"USB/Lexar/DFS/02 劉德華 - 孤星淚.dsf","trackType":"dsf"} Jul 12 11:06:27 volumio-x86-784 volumio[6197]: verbose: CURRENT POSITION 13 Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState stateService play Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreStateMachine::syncState currentStatus play Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: Received an update from plugin. extracting info from payload Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreStateMachine::pushState Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioPushState Jul 12 11:06:27 volumio-x86-784 volumio[6197]: info: ------------------------------ 1555ms Jul 12 11:06:31 volumio-x86-784 volumio[6197]: info: MyVolumio login type: Token Jul 12 11:06:35 volumio-x86-784 volumio[6197]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 12 11:06:35 volumio-x86-784 volumio[6197]: info: CoreCommandRouter::volumioGetState Jul 12 11:06:35 volumio-x86-784 volumio[6197]: info: CorePlayQueue::getTrack 13 Jul 12 11:06:54 volumio-x86-784 volumio[6197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 11:06:54 volumio-x86-784 volumio[6197]: Error: connect ETIMEDOUT 199.96.62.21:80 Jul 12 11:06:54 volumio-x86-784 volumio[6197]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 12 11:06:54 volumio-x86-784 volumio[6197]: errno: -110, Jul 12 11:06:54 volumio-x86-784 volumio[6197]: code: 'ETIMEDOUT', Jul 12 11:06:54 volumio-x86-784 volumio[6197]: syscall: 'connect', Jul 12 11:06:54 volumio-x86-784 volumio[6197]: address: '199.96.62.21', Jul 12 11:06:54 volumio-x86-784 volumio[6197]: port: 80 Jul 12 11:06:54 volumio-x86-784 volumio[6197]: } Jul 12 11:06:54 volumio-x86-784 volumio[6197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 11:06:55 volumio-x86-784 sudo[7102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-12 11:05 Jul 12 11:06:55 volumio-x86-784 sudo[7102]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"