-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2025-05-18 00:57:38 CST. -- May 18 00:56:02 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:56:02 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:56:02 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:56:02 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:02 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:02 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:56:09 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:09 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:56:09 volumio volumio[2613]: verbose: New Socket.io Connection to 192.168.32.117:3000 from 192.168.32.124 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:09 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 18 00:56:09 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 18 00:56:09 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E4%BA%91%E4%B8%8E%E6%B5%B7%20(%E6%A2%A6%E5%B9%BB%E8%A5%BF%E6%B8%B8%E9%BE%99%E5%AE%AB%E9%97%A8%E6%B4%BE%E6%9B%B2)/dd689d12-e299-44c1-8010-722c627fdc7f.jpg' May 18 00:56:09 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E4%BA%91%E4%B8%8E%E6%B5%B7%20(%E6%A2%A6%E5%B9%BB%E8%A5%BF%E6%B8%B8%E9%BE%99%E5%AE%AB%E9%97%A8%E6%B4%BE%E6%9B%B2)/dd689d12-e299-44c1-8010-722c627fdc7f.jpg' May 18 00:56:10 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:10 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:10 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E4%BA%91%E4%B8%8E%E6%B5%B7%20(%E6%A2%A6%E5%B9%BB%E8%A5%BF%E6%B8%B8%E9%BE%99%E5%AE%AB%E9%97%A8%E6%B4%BE%E6%9B%B2)/dd689d12-e299-44c1-8010-722c627fdc7f.jpg' May 18 00:56:10 volumio sudo[10671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 00:56:10 volumio sudo[10671]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 00:56:10 volumio sudo[10673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 00:56:10 volumio sudo[10671]: pam_unix(sudo:session): session closed for user root May 18 00:56:10 volumio sudo[10673]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 00:56:10 volumio sudo[10673]: pam_unix(sudo:session): session closed for user root May 18 00:56:10 volumio volumio[2613]: verbose: New Socket.io Connection to 192.168.32.117 from 192.168.32.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 18 00:56:10 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 00:56:11 volumio sudo[10691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 00:56:11 volumio sudo[10693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 00:56:11 volumio sudo[10691]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 00:56:11 volumio sudo[10691]: pam_unix(sudo:session): session closed for user root May 18 00:56:11 volumio sudo[10693]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 00:56:11 volumio sudo[10693]: pam_unix(sudo:session): session closed for user root May 18 00:56:11 volumio volumio[2613]: verbose: New Socket.io Connection to 192.168.32.117 from 192.168.32.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::volumioGetVisibleSources May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:11 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::volumioGetQueue May 18 00:56:11 volumio volumio[2613]: info: CoreStateMachine::getQueue May 18 00:56:11 volumio volumio[2613]: info: CorePlayQueue::getQueue May 18 00:56:11 volumio volumio[2613]: info: Listing playlists May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 00:56:11 volumio volumio[2613]: info: Received Get System Info May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:56:11 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:11 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:11 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 00:56:11 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 00:56:12 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 18 00:56:12 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F%3F%3F%3F/99f31d3c-b855-4810-b2e9-b4602fe72cc8.jpg' May 18 00:56:12 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F%3F/627932ce-e931-44c4-bd4a-351bd90880b6.jpg' May 18 00:56:12 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 00:56:12 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 00:56:13 volumio volumio[2613]: info: Received Get System Info May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:56:13 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:13 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:56:13 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:13 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:13 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:56:21 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 18 00:56:21 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:24 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 18 00:56:24 volumio volumio[2613]: info: CURURI: artists:// May 18 00:56:24 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:24 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/3031e793-e80c-4199-a1a4-084c14d87d7a.jpg' May 18 00:56:24 volumio volumio[2613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alan%20Walker/3fc75519-6f23-4b4d-bd86-29c9cfca7c84.jpg' May 18 00:56:26 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 18 00:56:26 volumio volumio[2613]: info: CURURI: music-library May 18 00:56:26 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:27 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 18 00:56:27 volumio volumio[2613]: info: CURURI: music-library/USB May 18 00:56:27 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:28 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 18 00:56:28 volumio volumio[2613]: info: CURURI: music-library/USB/1248F79948F77A35 May 18 00:56:28 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:29 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioReplaceandPlayItems May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::ClearQueue May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::stop May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::clearPlayQueue May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::saveQueue May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushQueue May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::addQueueItems May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::addQueueItems May 18 00:56:29 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/02. 暧昧.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/02. 暧昧.wav May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushQueue May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::saveQueue May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::updateTrackBlock May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrackBlock May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPlay May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::play index 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::addQueueItems May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::addQueueItems May 18 00:56:29 volumio volumio[2613]: info: Preload queue cleared May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/38. 趁早.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/38. 趁早.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/03. 遇到.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/03. 遇到.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/01. 从你的全世界路过.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/01. 从你的全世界路过.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/01. 渡月橋 ～君 想ふ～.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/01. 渡月橋 ～君 想ふ～.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/01. Faded.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/01. Faded.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/01. Into The Limelight (feat. Danyka Nadeau).wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/01. Into The Limelight (feat. Danyka Nadeau).wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/[unnamed]. Red Hot.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/[unnamed]. Red Hot.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/03. Goodbye Happiness.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/03. Goodbye Happiness.wav May 18 00:56:29 volumio volumio[2613]: info: Adding Item to queue: music-library/USB/1248F79948F77A35/01. The Power of Love.wav May 18 00:56:29 volumio volumio[2613]: info: Using cached record of: music-library/USB/1248F79948F77A35/01. The Power of Love.wav May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::stop May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushQueue May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::saveQueue May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::play index undefined May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::updateTrackBlock May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrackBlock May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::startPlaybackTimer May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::clearAddPlayTracks USB/1248F79948F77A35/02. 暧昧.wav May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand stop May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand stop took 3 milliseconds May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand clear May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:29 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand clear took 1 milliseconds May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand add "USB/1248F79948F77A35/02. 暧昧.wav" May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:29 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:29 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:29 volumio volumio[2613]: error: updateQueue error: null May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:29 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 6ms May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand add "USB/1248F79948F77A35/02. 暧昧.wav" took 4 milliseconds May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 5ms May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 4ms May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand play May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:29 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:29 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 9ms May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand play took 6 milliseconds May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 7ms May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 5ms May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand status took 200 milliseconds May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand status took 201 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand status took 200 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"?","uri":"USB/1248F79948F77A35/02. 暧昧.wav","trackType":"wav"} May 18 00:56:29 volumio volumio[2613]: verbose: CURRENT POSITION 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 212ms May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:29 volumio volumio[2613]: info: May 18 00:56:29 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand status took 13 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 10 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 11 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand status took 5 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand status took 5 milliseconds May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"?","uri":"USB/1248F79948F77A35/02. 暧昧.wav","trackType":"wav"} May 18 00:56:29 volumio volumio[2613]: verbose: CURRENT POSITION 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:29 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"?","uri":"USB/1248F79948F77A35/02. 暧昧.wav","trackType":"wav"} May 18 00:56:29 volumio volumio[2613]: verbose: CURRENT POSITION 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:29 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 260ms May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 260ms May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 47 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 46 milliseconds May 18 00:56:29 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 46 milliseconds May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:29 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"?","uri":"USB/1248F79948F77A35/02. 暧昧.wav","trackType":"wav"} May 18 00:56:29 volumio volumio[2613]: verbose: CURRENT POSITION 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:29 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"?","uri":"USB/1248F79948F77A35/02. 暧昧.wav","trackType":"wav"} May 18 00:56:29 volumio volumio[2613]: verbose: CURRENT POSITION 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:29 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"?","uri":"USB/1248F79948F77A35/02. 暧昧.wav","trackType":"wav"} May 18 00:56:29 volumio volumio[2613]: verbose: CURRENT POSITION 0 May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:29 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:29 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:29 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 137ms May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 129ms May 18 00:56:29 volumio volumio[2613]: info: ------------------------------ 129ms May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioNext May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::next May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::stop May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::stPlaybackTimer May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::updateTrackBlock May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrackBlock May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::serviceStop May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 0 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::serviceStop May 18 00:56:32 volumio volumio[2613]: info: ControllerMpd::stop May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand stop May 18 00:56:32 volumio volumio[2613]: info: sendMpdCommand stop took 28 milliseconds May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::play index undefined May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::startPlaybackTimer May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::clearAddPlayTracks USB/1248F79948F77A35/38. 趁早.wav May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand stop May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::updateTrackBlock May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrackBlock May 18 00:56:32 volumio volumio[2613]: info: May 18 00:56:32 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:32 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:32 volumio volumio[2613]: info: sendMpdCommand stop took 2 milliseconds May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand clear May 18 00:56:32 volumio volumio[2613]: info: May 18 00:56:32 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:32 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:32 volumio volumio[2613]: info: May 18 00:56:32 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:32 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:32 volumio volumio[2613]: info: May 18 00:56:32 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:32 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:32 volumio volumio[2613]: info: sendMpdCommand status took 4 milliseconds May 18 00:56:32 volumio volumio[2613]: info: sendMpdCommand clear took 3 milliseconds May 18 00:56:32 volumio volumio[2613]: info: sendMpdCommand status took 2 milliseconds May 18 00:56:32 volumio volumio[2613]: info: sendMpdCommand status took 13 milliseconds May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand add "USB/1248F79948F77A35/38. 趁早.wav" May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:32 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:32 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: 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 18 00:56:32 volumio volumio[2613]: verbose: CURRENT POSITION 1 May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::syncState stateService stop May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: No code May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: 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 18 00:56:32 volumio volumio[2613]: verbose: CURRENT POSITION 1 May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::syncState stateService stop May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: No code May 18 00:56:32 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:32 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:32 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:32 volumio volumio[2613]: info: ------------------------------ 83ms May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:33 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:33 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:33 volumio volumio[2613]: error: updateQueue error: null May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:33 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 94ms May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 80 milliseconds May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand add "USB/1248F79948F77A35/38. 趁早.wav" took 80 milliseconds May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand play May 18 00:56:33 volumio volumio[2613]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 101ms May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 174ms May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 173ms May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 170ms May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand play took 168 milliseconds May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:33 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:33 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand status took 43 milliseconds May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 44ms May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 43ms May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"???? 10?????? ","uri":"USB/1248F79948F77A35/38. 趁早.wav","trackType":"wav"} May 18 00:56:33 volumio volumio[2613]: verbose: CURRENT POSITION 1 May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 49ms May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:33 volumio volumio[2613]: info: May 18 00:56:33 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand status took 3 milliseconds May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand status took 1 milliseconds May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand status took 1 milliseconds May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 00:56:33 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:33 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"???? 10?????? ","uri":"USB/1248F79948F77A35/38. 趁早.wav","trackType":"wav"} May 18 00:56:33 volumio volumio[2613]: verbose: CURRENT POSITION 1 May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:33 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"???? 10?????? ","uri":"USB/1248F79948F77A35/38. 趁早.wav","trackType":"wav"} May 18 00:56:33 volumio volumio[2613]: verbose: CURRENT POSITION 1 May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:33 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:33 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"???? 10?????? ","uri":"USB/1248F79948F77A35/38. 趁早.wav","trackType":"wav"} May 18 00:56:33 volumio volumio[2613]: verbose: CURRENT POSITION 1 May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:33 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:33 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:33 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:33 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 65ms May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 64ms May 18 00:56:33 volumio volumio[2613]: info: ------------------------------ 64ms May 18 00:56:38 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:56:38 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:56:38 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:56:38 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:56:38 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:38 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioNext May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::next May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::stop May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::stPlaybackTimer May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::updateTrackBlock May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrackBlock May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::serviceStop May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 1 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::serviceStop May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::stop May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand stop May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand stop took 26 milliseconds May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::play index undefined May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::startPlaybackTimer May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::clearAddPlayTracks USB/1248F79948F77A35/03. 遇到.wav May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand stop May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::updateTrackBlock May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrackBlock May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand stop took 2 milliseconds May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand clear May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:48 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand status took 3 milliseconds May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand clear took 3 milliseconds May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand status took 2 milliseconds May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand add "USB/1248F79948F77A35/03. 遇到.wav" May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: 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 18 00:56:48 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::syncState stateService stop May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: No code May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: 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 18 00:56:48 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::syncState stateService stop May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: No code May 18 00:56:48 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:48 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:48 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 103ms May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 104ms May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:48 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:48 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:48 volumio volumio[2613]: error: updateQueue error: null May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:48 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 110ms May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 109 milliseconds May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand add "USB/1248F79948F77A35/03. 遇到.wav" took 109 milliseconds May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 8ms May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 6ms May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand play May 18 00:56:48 volumio volumio[2613]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 118ms May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:48 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces system playlist update May 18 00:56:48 volumio volumio[2613]: info: Ignoring MPD Status Update May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 21ms May 18 00:56:48 volumio volumio[2613]: info: sendMpdCommand play took 17 milliseconds May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 17ms May 18 00:56:48 volumio volumio[2613]: info: ------------------------------ 16ms May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:48 volumio volumio[2613]: info: May 18 00:56:48 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:48 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:48 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand status took 188 milliseconds May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:49 volumio volumio[2613]: info: May 18 00:56:49 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand status took 187 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand status took 185 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"????? ?????","uri":"USB/1248F79948F77A35/03. 遇到.wav","trackType":"wav"} May 18 00:56:49 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus stop May 18 00:56:49 volumio volumio[2613]: info: ------------------------------ 200ms May 18 00:56:49 volumio volumio[2613]: info: May 18 00:56:49 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:49 volumio volumio[2613]: info: May 18 00:56:49 volumio volumio[2613]: ---------------------------- MPD announces state update: player May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::getState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand status May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand status took 9 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 7 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 6 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand status took 3 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand status took 2 milliseconds May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseState May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"????? ?????","uri":"USB/1248F79948F77A35/03. 遇到.wav","trackType":"wav"} May 18 00:56:49 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:49 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"????? ?????","uri":"USB/1248F79948F77A35/03. 遇到.wav","trackType":"wav"} May 18 00:56:49 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:49 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: ------------------------------ 241ms May 18 00:56:49 volumio volumio[2613]: info: ------------------------------ 239ms May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 44 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 43 milliseconds May 18 00:56:49 volumio volumio[2613]: info: sendMpdCommand playlistinfo took 44 milliseconds May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:49 volumio volumio[2613]: verbose: ControllerMpd::parseTrackInfo May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"????? ?????","uri":"USB/1248F79948F77A35/03. 遇到.wav","trackType":"wav"} May 18 00:56:49 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:49 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"????? ?????","uri":"USB/1248F79948F77A35/03. 遇到.wav","trackType":"wav"} May 18 00:56:49 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:49 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: ControllerMpd::pushState May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::servicePushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??","artist":"???","album":"????? ?????","uri":"USB/1248F79948F77A35/03. 遇到.wav","trackType":"wav"} May 18 00:56:49 volumio volumio[2613]: verbose: CURRENT POSITION 2 May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState stateService play May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::syncState currentStatus play May 18 00:56:49 volumio volumio[2613]: info: Received an update from plugin. extracting info from payload May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: CoreStateMachine::pushState May 18 00:56:49 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:56:49 volumio volumio[2613]: info: CoreCommandRouter::volumioPushState May 18 00:56:49 volumio volumio[2613]: info: ------------------------------ 127ms May 18 00:56:49 volumio volumio[2613]: info: ------------------------------ 124ms May 18 00:56:49 volumio volumio[2613]: info: ------------------------------ 124ms May 18 00:57:08 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:57:08 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:57:08 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:57:08 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:57:08 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:57:08 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:57:38 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 00:57:38 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 00:57:38 volumio volumio[2613]: info: Discovery: Getting this device information May 18 00:57:38 volumio volumio[2613]: info: CoreCommandRouter::volumioGetState May 18 00:57:38 volumio volumio[2613]: info: CorePlayQueue::getTrack 2 May 18 00:57:38 volumio volumio[2613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 00:57:38 volumio volumio[2613]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 00:57:38 volumio volumio[2613]: Error: connect ETIMEDOUT 202.160.128.195:80 May 18 00:57:38 volumio volumio[2613]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 18 00:57:38 volumio volumio[2613]: errno: -110, May 18 00:57:38 volumio volumio[2613]: code: 'ETIMEDOUT', May 18 00:57:38 volumio volumio[2613]: syscall: 'connect', May 18 00:57:38 volumio volumio[2613]: address: '202.160.128.195', May 18 00:57:38 volumio volumio[2613]: port: 80 May 18 00:57:38 volumio volumio[2613]: } May 18 00:57:38 volumio volumio[2613]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 00:57:38 volumio sudo[10891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 00:56 May 18 00:57:38 volumio sudo[10891]: 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="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"