-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Mon 2025-05-12 22:05:24 CST. -- May 12 22:04:04 qsva volumio[3597]: Uploading: Screenshot_2025-02-13-13-58-54-82_3d819ca0aafc750ced08a57fa1c9e1f4.jpg May 12 22:04:04 qsva volumio[3597]: Background Successfully Uploaded May 12 22:04:04 qsva volumio[3597]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 12 22:04:04 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , generateThumbnails May 12 22:04:04 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds May 12 22:04:04 qsva volumio[3597]: info: Creating Thumbnail for file Screenshot_2025-02-13-13-58-54-82_3d819ca0aafc750ced08a57fa1c9e1f4.jpg : /data/backgrounds/thumbnail-Screenshot_2025-02-13-13-58-54-82_3d819ca0aafc750ced08a57fa1c9e1f4.jpg May 12 22:04:10 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , setBackgrounds May 12 22:04:10 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds May 12 22:04:10 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:16 qsva volumio[3597]: info: Retrieving Cloud Streaming UI May 12 22:04:16 qsva volumio[3597]: info: Getting Tidal Cloud Configuration May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:16 qsva volumio[3597]: info: Getting Qobuz Cloud Configuration May 12 22:04:16 qsva volumio[3597]: info: Asking plugin for UI Config May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:16 qsva volumio[3597]: info: Getting Spotify Cloud Configuration May 12 22:04:16 qsva volumio[3597]: info: Asking plugin for UI Config May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:16 qsva volumio[3597]: info: Saving Spotify Acccount May 12 22:04:16 qsva volumio[3597]: info: Got Tidal Cloud Configuration May 12 22:04:16 qsva volumio[3597]: info: Got it May 12 22:04:16 qsva volumio[3597]: info: Got it May 12 22:04:16 qsva volumio[3597]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:16 qsva volumio[3597]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::volumioGetBrowseSources May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::volumioGetBrowseSources May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::volumioGetBrowseSources May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 12 22:04:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 12 22:04:24 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:30 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:04:37 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 12 22:04:38 qsva volumio[3597]: info: CoreCommandRouter::Close All Modals sent May 12 22:04:41 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 12 22:04:41 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 22:04:42 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri May 12 22:04:42 qsva volumio[3597]: info: Preload queue cleared May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/[hi-res]陈奕迅-孤勇者.wav May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/胡夏、郁可唯 - 知否知否.flac May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/玫瑰也曾温柔过.mp3 May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/赵雷 - 成都.flac May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/小猫日记 - 踏浪 (小小的一片云呀).flac May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/野哥 - 姑娘我怎能忘.mp3 May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/野哥 - 姑娘我怎能忘.flac May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/姑娘我怎能忘.mp3 May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/张碧晨 - 在故事的最终.flac May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/张碧晨、王赫野 - 这世界那么多人 (Live).flac May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/远山少年 - 窝窝.mp3 May 12 22:04:42 qsva volumio[3597]: info: Preloading song: mnt/INTERNAL/许梦宸 - 天下.flac May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/[hi-res]陈奕迅-孤勇者.wav in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%3F%3F%3F%3F%D1%B8/%D3%A2%3F%3F%EF%BF%BD%EF%BF%BD%3F%CB%A3%3F%CB%AB%3F%3F%D6%AE%D5%BD/extralarge&path=%2Fmnt%2FINTERNAL%2F%5Bhi-res%5D%E9%99%88%E5%A5%95%E8%BF%85-%E5%AD%A4%E5%8B%87%E8%80%85.wav&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/[hi-res]陈奕迅-孤勇者.wav May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/胡夏、郁可唯 - 知否知否.flac in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E8%83%A1%E5%A4%8F%E3%80%81%E9%83%81%E5%8F%AF%E5%94%AF/%E7%9F%A5%E5%90%A6%E7%9F%A5%E5%90%A6/extralarge&path=%2Fmnt%2FINTERNAL%2F%E8%83%A1%E5%A4%8F%E3%80%81%E9%83%81%E5%8F%AF%E5%94%AF%20-%20%E7%9F%A5%E5%90%A6%E7%9F%A5%E5%90%A6.flac&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/胡夏、郁可唯 - 知否知否.flac May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/玫瑰也曾温柔过.mp3 in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E7%8E%8B%E5%A8%9C/%E7%8E%AB%E7%91%B0%E4%B9%9F%E6%9B%BE%E6%B8%A9%E6%9F%94%E8%BF%87/extralarge&path=%2Fmnt%2FINTERNAL%2F%E7%8E%AB%E7%91%B0%E4%B9%9F%E6%9B%BE%E6%B8%A9%E6%9F%94%E8%BF%87.mp3&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/玫瑰也曾温柔过.mp3 May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/赵雷 - 成都.flac in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E8%B5%B5%E9%9B%B7/%E6%97%A0%E6%B3%95%E9%95%BF%E5%A4%A7/extralarge&path=%2Fmnt%2FINTERNAL%2F%E8%B5%B5%E9%9B%B7%20-%20%E6%88%90%E9%83%BD.flac&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/赵雷 - 成都.flac May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/小猫日记 - 踏浪 (小小的一片云呀).flac in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E5%B0%8F%E7%8C%AB%E6%97%A5%E8%AE%B0/%E5%B0%8F%E5%B0%8F%E7%9A%84%E4%B8%80%E7%89%87%E4%BA%91%E5%91%80%EF%BC%88%E6%85%A2%E6%85%A2%E5%9C%B0%E8%B5%B0%E8%BF%87%E6%9D%A5%EF%BC%89/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%B0%8F%E7%8C%AB%E6%97%A5%E8%AE%B0%20-%20%E8%B8%8F%E6%B5%AA%20(%E5%B0%8F%E5%B0%8F%E7%9A%84%E4%B8%80%E7%89%87%E4%BA%91%E5%91%80).flac&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/小猫日记 - 踏浪 (小小的一片云呀).flac May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/野哥 - 姑娘我怎能忘.mp3 in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E9%87%8E%E5%93%A5/%E5%A7%91%E5%A8%98%E6%88%91%E6%80%8E%E8%83%BD%E5%BF%98/extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%87%8E%E5%93%A5%20-%20%E5%A7%91%E5%A8%98%E6%88%91%E6%80%8E%E8%83%BD%E5%BF%98.mp3&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/野哥 - 姑娘我怎能忘.mp3 May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/野哥 - 姑娘我怎能忘.flac in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E9%87%8E%E5%93%A5/%E5%A7%91%E5%A8%98%E6%88%91%E6%80%8E%E8%83%BD%E5%BF%98/extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%87%8E%E5%93%A5%20-%20%E5%A7%91%E5%A8%98%E6%88%91%E6%80%8E%E8%83%BD%E5%BF%98.flac&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/野哥 - 姑娘我怎能忘.flac May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/姑娘我怎能忘.mp3 in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E9%87%8E%E5%93%A5/%E5%A7%91%E5%A8%98%E6%88%91%E6%80%8E%E8%83%BD%E5%BF%98/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%A7%91%E5%A8%98%E6%88%91%E6%80%8E%E8%83%BD%E5%BF%98.mp3&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/姑娘我怎能忘.mp3 May 12 22:04:42 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 12 22:04:42 qsva volumio[3597]: info: Exploding uri mnt/INTERNAL/张碧晨 - 在故事的最终.flac in service mpd May 12 22:04:42 qsva volumio[3597]: info: ALBUMART /albumart?cacheid=37&web=%E5%BC%A0%E7%A2%A7%E6%99%A8/%E5%9C%A8%E6%95%85%E4%BA%8B%E7%9A%84%E6%9C%80%E7%BB%88/extralarge&path=%2Fmnt%2FINTERNAL%2F%E5%BC%A0%E7%A2%A7%E6%99%A8%20-%20%E5%9C%A8%E6%95%85%E4%BA%8B%E7%9A%84%E6%9C%80%E7%BB%88.flac&metadata=false May 12 22:04:42 qsva volumio[3597]: info: URI /mnt/INTERNAL/张碧晨 - 在故事的最终.flac May 12 22:04:42 qsva volumio[3597]: info: Preload queue cleared May 12 22:04:43 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:04:43 qsva volumio[3597]: info: CURURI: genres:// May 12 22:04:43 qsva volumio[3597]: info: Preload queue cleared May 12 22:04:44 qsva volumio[3597]: info: Preload queue cleared May 12 22:04:47 qsva sudo[4142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 22:04:47 qsva sudo[4142]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 22:04:47 qsva sudo[4142]: pam_unix(sudo:session): session closed for user root May 12 22:04:47 qsva sudo[4145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 22:04:47 qsva sudo[4145]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 22:04:47 qsva sudo[4145]: pam_unix(sudo:session): session closed for user root May 12 22:04:47 qsva volumio[3597]: verbose: New Socket.io Connection to 192.168.145.37 from 192.168.145.96 UA: Mozilla/5.0 (Linux; U; Android 14; zh-cn; OPD2101 Build/UKQ1.230924.001) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/115.0.5790.168 Android 14 Safari/537.36 HeyTapBrowser/40.9.8.1 Engine version: 3 Transport: polling Total Clients: 6 May 12 22:04:47 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 22:04:47 qsva volumio[3597]: info: CoreCommandRouter::volumioGetState May 12 22:04:47 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:04:47 qsva volumio[3597]: info: CoreCommandRouter::volumioGetVisibleSources May 12 22:04:47 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 22:04:47 qsva volumio[3597]: info: CoreCommandRouter::volumioGetQueue May 12 22:04:47 qsva volumio[3597]: info: CoreStateMachine::getQueue May 12 22:04:47 qsva volumio[3597]: info: CorePlayQueue::getQueue May 12 22:04:47 qsva volumio[3597]: info: Listing playlists May 12 22:04:56 qsva sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 22:04:56 qsva sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 22:04:56 qsva sudo[4169]: pam_unix(sudo:session): session closed for user root May 12 22:04:56 qsva sudo[4172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 22:04:56 qsva sudo[4172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 22:04:56 qsva sudo[4172]: pam_unix(sudo:session): session closed for user root May 12 22:04:56 qsva volumio[3597]: verbose: New Socket.io Connection to 192.168.145.37 from 192.168.145.96 UA: Mozilla/5.0 (Linux; U; Android 14; zh-cn; OPD2101 Build/UKQ1.230924.001) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/115.0.5790.168 Android 14 Safari/537.36 HeyTapBrowser/40.9.8.1 Engine version: 3 Transport: polling Total Clients: 6 May 12 22:04:56 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 22:04:56 qsva volumio[3597]: info: CoreCommandRouter::volumioGetState May 12 22:04:56 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:04:56 qsva volumio[3597]: info: CoreCommandRouter::volumioGetVisibleSources May 12 22:04:56 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 22:04:56 qsva volumio[3597]: info: CoreCommandRouter::volumioGetQueue May 12 22:04:56 qsva volumio[3597]: info: CoreStateMachine::getQueue May 12 22:04:56 qsva volumio[3597]: info: CorePlayQueue::getQueue May 12 22:04:56 qsva volumio[3597]: info: Listing playlists May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioSeek May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::seek May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/张远-嘉宾.wav","service":"mpd","name":"张远-嘉宾.wav","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=37&web=//extralarge&path=%2FINTERNAL&metadata=false","duration":334,"trackType":"wav","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::startPlaybackTimer May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::seek May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: error: null May 12 22:05:10 qsva volumio[3597]: info: May 12 22:05:10 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:10 qsva volumio[3597]: info: May 12 22:05:10 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:10 qsva volumio[3597]: info: May 12 22:05:10 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:10 qsva volumio[3597]: info: sendMpdCommand status took 14 milliseconds May 12 22:05:10 qsva volumio[3597]: info: sendMpdCommand status took 12 milliseconds May 12 22:05:10 qsva volumio[3597]: info: sendMpdCommand status took 12 milliseconds May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:10 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 2 milliseconds May 12 22:05:10 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 2 milliseconds May 12 22:05:10 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 2 milliseconds May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:10 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185960,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张远-嘉宾.wav","artist":null,"album":null,"uri":"INTERNAL/张远-嘉宾.wav","trackType":"wav"} May 12 22:05:10 qsva volumio[3597]: verbose: CURRENT POSITION 0 May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:10 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185960,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张远-嘉宾.wav","artist":null,"album":null,"uri":"INTERNAL/张远-嘉宾.wav","trackType":"wav"} May 12 22:05:10 qsva volumio[3597]: verbose: CURRENT POSITION 0 May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:10 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185960,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张远-嘉宾.wav","artist":null,"album":null,"uri":"INTERNAL/张远-嘉宾.wav","trackType":"wav"} May 12 22:05:10 qsva volumio[3597]: verbose: CURRENT POSITION 0 May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:10 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:10 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:10 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:10 qsva volumio[3597]: info: ------------------------------ 58ms May 12 22:05:10 qsva volumio[3597]: info: ------------------------------ 57ms May 12 22:05:10 qsva volumio[3597]: info: ------------------------------ 56ms May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPlay May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::play index 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::stop May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::stPlaybackTimer May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::updateTrackBlock May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrackBlock May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::serviceStop May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 0 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::serviceStop May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::stop May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand stop took 28 milliseconds May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::play index undefined May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::startPlaybackTimer May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/茶花开了.mp3 May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand stop took 1 milliseconds May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand clear May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces system playlist update May 12 22:05:16 qsva volumio[3597]: info: Ignoring MPD Status Update May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 4 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand clear took 4 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 3 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 3 milliseconds May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/茶花开了.mp3" May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: 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} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService stop May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus stop May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: No code May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: 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} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService stop May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus stop May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: No code May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 39ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 38ms May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces system playlist update May 12 22:05:16 qsva volumio[3597]: info: Ignoring MPD Status Update May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces system playlist update May 12 22:05:16 qsva volumio[3597]: info: Ignoring MPD Status Update May 12 22:05:16 qsva volumio[3597]: error: updateQueue error: null May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces system playlist update May 12 22:05:16 qsva volumio[3597]: info: Ignoring MPD Status Update May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 41ms May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 39 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand add "INTERNAL/茶花开了.mp3" took 39 milliseconds May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 4ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 3ms May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand play May 12 22:05:16 qsva volumio[3597]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 48ms May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces system playlist update May 12 22:05:16 qsva volumio[3597]: info: Ignoring MPD Status Update May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces system playlist update May 12 22:05:16 qsva volumio[3597]: info: Ignoring MPD Status Update May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 14ms May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand play took 11 milliseconds May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 10ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 10ms May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 4 milliseconds May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 3 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 3 milliseconds May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: May 12 22:05:16 qsva volumio[3597]: ---------------------------- MPD announces state update: player May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::getState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand status May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 7 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 5 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 5 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 4 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand status took 4 milliseconds May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseState May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"茶花开了.mp3","artist":null,"album":null,"uri":"INTERNAL/茶花开了.mp3","trackType":"mp3"} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus stop May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"茶花开了.mp3","artist":null,"album":null,"uri":"INTERNAL/茶花开了.mp3","trackType":"mp3"} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:16 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"茶花开了.mp3","artist":null,"album":null,"uri":"INTERNAL/茶花开了.mp3","trackType":"mp3"} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:16 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 27ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 40ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 39ms May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 29 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 29 milliseconds May 12 22:05:16 qsva volumio[3597]: info: sendMpdCommand playlistinfo took 28 milliseconds May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: verbose: ControllerMpd::parseTrackInfo May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"茶花开了.mp3","artist":null,"album":null,"uri":"INTERNAL/茶花开了.mp3","trackType":"mp3"} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:16 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"茶花开了.mp3","artist":null,"album":null,"uri":"INTERNAL/茶花开了.mp3","trackType":"mp3"} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:16 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ControllerMpd::pushState May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::servicePushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"茶花开了.mp3","artist":null,"album":null,"uri":"INTERNAL/茶花开了.mp3","trackType":"mp3"} May 12 22:05:16 qsva volumio[3597]: verbose: CURRENT POSITION 1 May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState stateService play May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::syncState currentStatus play May 12 22:05:16 qsva volumio[3597]: info: Received an update from plugin. extracting info from payload May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: CoreStateMachine::pushState May 12 22:05:16 qsva volumio[3597]: info: CorePlayQueue::getTrack 1 May 12 22:05:16 qsva volumio[3597]: info: CoreCommandRouter::volumioPushState May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 75ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 71ms May 12 22:05:16 qsva volumio[3597]: info: ------------------------------ 71ms May 12 22:05:23 qsva volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 22:05:24 qsva volumio[3597]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 22:05:24 qsva volumio[3597]: Error: connect ETIMEDOUT 185.60.219.41:80 May 12 22:05:24 qsva volumio[3597]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 12 22:05:24 qsva volumio[3597]: errno: -110, May 12 22:05:24 qsva volumio[3597]: code: 'ETIMEDOUT', May 12 22:05:24 qsva volumio[3597]: syscall: 'connect', May 12 22:05:24 qsva volumio[3597]: address: '185.60.219.41', May 12 22:05:24 qsva volumio[3597]: port: 80 May 12 22:05:24 qsva volumio[3597]: } May 12 22:05:24 qsva volumio[3597]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 22:05:24 qsva sudo[4257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 22:04 May 12 22:05:24 qsva sudo[4257]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"