-- Logs begin at Tue 2025-05-06 12:37:18 CST, end at Tue 2025-05-06 12:44:36 CST. -- May 06 12:43:10 volumio sudo[2805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 12:43:10 volumio sudo[2805]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:43:10 volumio sudo[2805]: pam_unix(sudo:session): session closed for user root May 06 12:43:10 volumio sudo[2809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 12:43:10 volumio sudo[2809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:43:10 volumio sudo[2809]: pam_unix(sudo:session): session closed for user root May 06 12:43:13 volumio sudo[2813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 12:43:13 volumio sudo[2813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:43:13 volumio sudo[2813]: pam_unix(sudo:session): session closed for user root May 06 12:43:13 volumio sudo[2818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 12:43:13 volumio sudo[2818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:43:13 volumio sudo[2818]: pam_unix(sudo:session): session closed for user root May 06 12:43:13 volumio sudo[2821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 12:43:13 volumio sudo[2821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:43:13 volumio sudo[2821]: pam_unix(sudo:session): session closed for user root May 06 12:43:13 volumio sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 12:43:13 volumio sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:43:13 volumio sudo[2826]: pam_unix(sudo:session): session closed for user root May 06 12:43:25 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:43:25 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:43:25 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:43:25 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:25 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:25 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:43:26 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 06 12:43:26 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 06 12:43:26 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 06 12:43:26 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 06 12:43:27 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetVisibleSources May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 12:43:27 volumio volumio[1122]: info: Received Get System Info May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:43:27 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: Listing playlists May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 12:43:27 volumio volumio[1122]: info: Received Get System Info May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:43:27 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetVisibleSources May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 12:43:27 volumio volumio[1122]: info: Received Get System Info May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:43:27 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:27 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:27 volumio volumio[1122]: info: Listing playlists May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 06 12:43:27 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 06 12:43:29 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 06 12:43:29 volumio ntpd[1121]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 06 12:43:46 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::volumioGetVisibleSources May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 12:43:46 volumio volumio[1122]: info: Received Get System Info May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:43:46 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:43:46 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:46 volumio volumio[1122]: info: Listing playlists May 06 12:43:46 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 06 12:43:46 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76:3000 from 192.168.31.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::volumioPlay May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::resume May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 06 12:43:48 volumio volumio[1122]: info: May 06 12:43:48 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:43:48 volumio volumio[1122]: info: sendMpdCommand play took 8 milliseconds May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:43:48 volumio volumio[1122]: info: May 06 12:43:48 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:43:48 volumio volumio[1122]: info: May 06 12:43:48 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:43:48 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:43:48 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:43:48 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:43:48 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65601,"duration":201,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"¶ÀÁ¢","artist":"ÃÛÑ©Þ±ç÷","album":null,"uri":"USB/5E84-A769/蜜雪薇琪 - 独立 [mqms2].mp3","trackType":"mp3"} May 06 12:43:48 volumio volumio[1122]: verbose: CURRENT POSITION 3 May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus pause May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65601,"duration":201,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"¶ÀÁ¢","artist":"ÃÛÑ©Þ±ç÷","album":null,"uri":"USB/5E84-A769/蜜雪薇琪 - 独立 [mqms2].mp3","trackType":"mp3"} May 06 12:43:48 volumio volumio[1122]: verbose: CURRENT POSITION 3 May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:43:48 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:43:48 volumio volumio[1122]: info: ------------------------------ 44ms May 06 12:43:48 volumio volumio[1122]: info: ------------------------------ 42ms May 06 12:43:48 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 37 milliseconds May 06 12:43:48 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:43:48 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65601,"duration":201,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"¶ÀÁ¢","artist":"ÃÛÑ©Þ±ç÷","album":null,"uri":"USB/5E84-A769/蜜雪薇琪 - 独立 [mqms2].mp3","trackType":"mp3"} May 06 12:43:48 volumio volumio[1122]: verbose: CURRENT POSITION 3 May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:43:48 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:43:48 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:43:48 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:43:48 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:43:48 volumio volumio[1122]: info: ------------------------------ 68ms May 06 12:43:50 volumio volumio[1122]: info: CoreCommandRouter::volumioGetQueue May 06 12:43:50 volumio volumio[1122]: info: CoreStateMachine::getQueue May 06 12:43:50 volumio volumio[1122]: info: CorePlayQueue::getQueue May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Suara/AQUAPLUS%20VOCAL%20COLLECTION%20VOL.8/74aa56a5-e67b-45b0-ba02-d28813470a7d.png' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A5%BF%E9%87%8E%E3%82%AB%E3%83%8A/Always/253f44d9-908c-4b9d-8cc4-bb4590762623.png' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/James%20Blunt/Back%20To%20Bedlam/c313a37d-a955-4ae7-801f-37611be5b2ae.jpg' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A5%BF%E9%87%8E%E3%82%AB%E3%83%8A/Believe/a2977253-bce8-480b-98c1-12cae41d8195.jpg' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Amy%20Winehouse/Back%20To%20Black/c0f60159-f577-4a4c-b02c-31b4f91a1cd0.png' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A5%BF%E9%87%8E%E5%8A%A0%E5%A5%88/Best%20Friend/24bbc2c7-22dd-43ba-8719-131934cdb8ed.jpg' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A5%BF%E9%87%8E%E3%82%AB%E3%83%8A/Believe/a2977253-bce8-480b-98c1-12cae41d8195.jpg' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Suara/AQUAPLUS%20VOCAL%20COLLECTION%20VOL.8/74aa56a5-e67b-45b0-ba02-d28813470a7d.png' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/James%20Blunt/Back%20To%20Bedlam/c313a37d-a955-4ae7-801f-37611be5b2ae.jpg' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Amy%20Winehouse/Back%20To%20Black/c0f60159-f577-4a4c-b02c-31b4f91a1cd0.png' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A5%BF%E9%87%8E%E3%82%AB%E3%83%8A/Always/253f44d9-908c-4b9d-8cc4-bb4590762623.png' May 06 12:43:51 volumio volumio[1122]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A5%BF%E9%87%8E%E5%8A%A0%E5%A5%88/Best%20Friend/24bbc2c7-22dd-43ba-8719-131934cdb8ed.jpg' May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:44:04 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:04 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:44:04 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76:3000 from 192.168.31.57 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:04 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 06 12:44:04 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 06 12:44:05 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:44:05 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:44:05 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:44:05 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:05 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:05 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:44:07 volumio sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 12:44:07 volumio sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:44:07 volumio sudo[2972]: pam_unix(sudo:session): session closed for user root May 06 12:44:07 volumio sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 12:44:07 volumio sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:44:07 volumio sudo[2975]: pam_unix(sudo:session): session closed for user root May 06 12:44:07 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 06 12:44:08 volumio sudo[2980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 12:44:08 volumio sudo[2980]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:44:08 volumio sudo[2982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 12:44:08 volumio sudo[2982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:44:08 volumio sudo[2980]: pam_unix(sudo:session): session closed for user root May 06 12:44:08 volumio sudo[2982]: pam_unix(sudo:session): session closed for user root May 06 12:44:08 volumio volumio[1122]: verbose: New Socket.io Connection to 192.168.31.76 from 192.168.31.57 UA: Mozilla/5.0 (Linux; Android 15; 2211133C Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::volumioGetVisibleSources May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:08 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 12:44:08 volumio volumio[1122]: info: Received Get System Info May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:44:08 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:08 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:08 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:08 volumio volumio[1122]: info: Listing playlists May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 12:44:08 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioNext May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::next May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::stop May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::serviceStop May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 3 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::serviceStop May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::stop May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand stop took 17 milliseconds May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3 May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 13 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand stop took 8 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 5 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:09 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand clear took 4 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3" May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"¶ÀÁ¢","artist":"ÃÛÑ©Þ±ç÷","album":null,"uri":"USB/5E84-A769/蜜雪薇琪 - 独立 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: No code May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:09 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 63ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 54ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 52ms May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:09 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:09 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:09 volumio volumio[1122]: error: updateQueue error: null May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:09 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 48ms May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand add "USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3" took 46 milliseconds May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 5ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 4ms May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:09 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:09 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 10ms May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand play took 7 milliseconds May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 6ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 6ms May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 10 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 9 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":606,"duration":385,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":606,"duration":385,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:09 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 33ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 57ms May 06 12:44:09 volumio volumio[1122]: info: May 06 12:44:09 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 57 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 57 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 57 milliseconds May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand status took 8 milliseconds May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":732,"duration":385,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:09 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":385,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:09 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":385,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:09 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 179ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 179ms May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 172ms May 06 12:44:09 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 123 milliseconds May 06 12:44:09 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:09 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":385,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:09 volumio volumio[1122]: verbose: CURRENT POSITION 4 May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:09 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:09 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:09 volumio volumio[1122]: info: ------------------------------ 171ms May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 06 12:44:09 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 12:44:10 volumio volumio[1122]: info: Received Get System Info May 06 12:44:10 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:44:10 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:44:10 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:44:10 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:10 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:10 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioNext May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::next May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::stop May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::serviceStop May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 4 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::serviceStop May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::stop May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand stop took 20 milliseconds May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3 May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 9 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand stop took 5 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:11 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand clear took 3 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3" May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"周晓鸥 - 爱不爱我 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/周晓鸥 - 爱不爱我 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: No code May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 48ms May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 42ms May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:11 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:11 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:11 volumio volumio[1122]: error: updateQueue error: null May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:11 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 40 milliseconds May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 38ms May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand add "USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3" took 36 milliseconds May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 4ms May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 3ms May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 06 12:44:11 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 46ms May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:11 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:11 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 9ms May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand play took 7 milliseconds May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 5ms May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 7 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 6 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 6 milliseconds May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: May 06 12:44:11 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 9 milliseconds May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 14 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 14 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 13 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 12 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand status took 12 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 10 milliseconds May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:11 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:11 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:11 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 56ms May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 96ms May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 95ms May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 94ms May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 74 milliseconds May 06 12:44:11 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 74 milliseconds May 06 12:44:11 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:11 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":326,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:11 volumio volumio[1122]: verbose: CURRENT POSITION 5 May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:11 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:11 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:11 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 136ms May 06 12:44:11 volumio volumio[1122]: info: ------------------------------ 136ms May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioNext May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::next May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::stop May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::serviceStop May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 5 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::serviceStop May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::stop May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand stop took 24 milliseconds May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3 May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 5 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand stop took 5 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:12 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand clear took 3 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3" May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 听说爱情回来过 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 听说爱情回来过 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: No code May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 38ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 36ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 34ms May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:12 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:12 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:12 volumio volumio[1122]: error: updateQueue error: null May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:12 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 34ms May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand add "USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3" took 32 milliseconds May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 4ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 3ms May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:12 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:12 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 7ms May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand play took 5 milliseconds May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 5ms May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: May 06 12:44:12 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 6 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:12 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:12 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 57ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 82ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 81ms May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 72 milliseconds May 06 12:44:12 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 72 milliseconds May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:12 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:12 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":282,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:12 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:12 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:12 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:12 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 147ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 144ms May 06 12:44:12 volumio volumio[1122]: info: ------------------------------ 144ms May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioSeek May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::seek May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: TRACKBLOCK {"uri":"mnt/USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","service":"mpd","name":"陈明 - 情人的眼泪 [mqms2].mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=638&web=//extralarge&path=%2FUSB%2F5E84-A769&metadata=false","duration":270,"trackType":"mp3","samplerate":"48 kHz","bitdepth":"24 bit","channels":2} May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::seek May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: error: null May 06 12:44:14 volumio volumio[1122]: info: May 06 12:44:14 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:14 volumio volumio[1122]: info: May 06 12:44:14 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:14 volumio volumio[1122]: info: May 06 12:44:14 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:14 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:14 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:14 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:14 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:14 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":155000,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:14 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:14 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":155000,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:14 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:14 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: info: ------------------------------ 46ms May 06 12:44:14 volumio volumio[1122]: info: ------------------------------ 46ms May 06 12:44:14 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 41 milliseconds May 06 12:44:14 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:14 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":155000,"duration":270,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:14 volumio volumio[1122]: verbose: CURRENT POSITION 6 May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:14 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:14 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:14 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:14 volumio volumio[1122]: info: ------------------------------ 65ms May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioNext May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::next May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::stop May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::serviceStop May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 6 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::serviceStop May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::stop May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand stop took 15 milliseconds May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3 May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand stop took 3 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:15 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 3 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand clear took 3 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3" May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈明 - 情人的眼泪 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/陈明 - 情人的眼泪 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: No code May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:15 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 41ms May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 38ms May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:15 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:15 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:15 volumio volumio[1122]: error: updateQueue error: null May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:15 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 40ms May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand add "USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3" took 38 milliseconds May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 6ms May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 6ms May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:15 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:15 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 10ms May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand play took 6 milliseconds May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 6ms May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 5ms May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: May 06 12:44:15 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 6 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 4 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":364,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":364,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:15 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":364,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:15 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 31ms May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 67ms May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 67ms May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand status took 61 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 59 milliseconds May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 58 milliseconds May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41,"duration":364,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:15 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":313,"duration":364,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:15 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 113ms May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 111ms May 06 12:44:15 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 57 milliseconds May 06 12:44:15 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:15 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":428,"duration":364,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:15 volumio volumio[1122]: verbose: CURRENT POSITION 7 May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:15 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:15 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:15 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:15 volumio volumio[1122]: info: ------------------------------ 150ms May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioNext May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::next May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::stop May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::stPlaybackTimer May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::serviceStop May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 7 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::serviceStop May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::stop May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand stop took 18 milliseconds May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::play index undefined May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3 May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::updateTrackBlock May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrackBlock May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 7 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand stop took 5 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"黄绮珊 - 离不开你 [mqms2].mp3","artist":null,"album":null,"uri":"USB/5E84-A769/黄绮珊 - 离不开你 [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService stop May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: No code May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 45ms May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:16 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand clear took 37 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 36 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 37 milliseconds May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3" May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:16 volumio volumio[1122]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 52ms May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 51ms May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:16 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:16 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:16 volumio volumio[1122]: error: updateQueue error: null May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:16 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 18ms May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand add "USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3" took 18 milliseconds May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 8ms May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 7ms May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand play May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:16 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces system playlist update May 06 12:44:16 volumio volumio[1122]: info: Ignoring MPD Status Update May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 6ms May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand play took 5 milliseconds May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 3ms May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 2ms May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 5 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 4 milliseconds May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 6 milliseconds May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: May 06 12:44:16 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 11 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 10 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 9 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 6 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 3 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand status took 2 milliseconds May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus stop May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:16 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:16 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 38ms May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 68ms May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 53 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 52 milliseconds May 06 12:44:16 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 52 milliseconds May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:16 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:16 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:16 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:16 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:16 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:16 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 146ms May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 135ms May 06 12:44:16 volumio volumio[1122]: info: ------------------------------ 134ms May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioSeek May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::seek May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: TRACKBLOCK {"uri":"mnt/USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","service":"mpd","name":"CD Track 02","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=638&web=//extralarge&path=%2FUSB%2F5E84-A769&metadata=false","duration":257,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::startPlaybackTimer May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::seek May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: error: null May 06 12:44:18 volumio volumio[1122]: info: May 06 12:44:18 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:18 volumio volumio[1122]: info: May 06 12:44:18 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:18 volumio volumio[1122]: info: May 06 12:44:18 volumio volumio[1122]: ---------------------------- MPD announces state update: player May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::getState May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand status May 06 12:44:18 volumio volumio[1122]: info: sendMpdCommand status took 7 milliseconds May 06 12:44:18 volumio volumio[1122]: info: sendMpdCommand status took 6 milliseconds May 06 12:44:18 volumio volumio[1122]: info: sendMpdCommand status took 5 milliseconds May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::parseState May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:44:18 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:18 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 12:44:18 volumio volumio[1122]: info: sendMpdCommand playlistinfo took 1 milliseconds May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:18 volumio volumio[1122]: verbose: ControllerMpd::parseTrackInfo May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147000,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:18 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:18 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147000,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:18 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:18 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: info: ControllerMpd::pushState May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::servicePushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147000,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"CD Track 02","artist":null,"album":null,"uri":"USB/5E84-A769/乃木坂46 - 何もできずにそばにいる [mqms2].mp3","trackType":"mp3"} May 06 12:44:18 volumio volumio[1122]: verbose: CURRENT POSITION 8 May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::syncState stateService play May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::syncState currentStatus play May 06 12:44:18 volumio volumio[1122]: info: Received an update from plugin. extracting info from payload May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:18 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:18 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:18 volumio volumio[1122]: info: ------------------------------ 65ms May 06 12:44:18 volumio volumio[1122]: info: ------------------------------ 65ms May 06 12:44:18 volumio volumio[1122]: info: ------------------------------ 64ms May 06 12:44:20 volumio volumio[1122]: info: VolumeController::SetAlsaVolume18 May 06 12:44:21 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:21 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:21 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:21 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:21 volumio volumio[1122]: info: VolumeController::SetAlsaVolume29 May 06 12:44:21 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:21 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:21 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:21 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:24 volumio volumio[1122]: info: VolumeController::SetAlsaVolume- May 06 12:44:24 volumio volumio[1122]: info: CoreStateMachine::pushState May 06 12:44:24 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:24 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:44:24 volumio volumio[1122]: info: CoreCommandRouter::volumioPushState May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 06 12:44:26 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 06 12:44:34 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:44:34 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:44:34 volumio volumio[1122]: info: Discovery: Getting this device information May 06 12:44:34 volumio volumio[1122]: info: CoreCommandRouter::volumioGetState May 06 12:44:34 volumio volumio[1122]: info: CorePlayQueue::getTrack 8 May 06 12:44:34 volumio volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:44:36 volumio volumio[1122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 12:44:36 volumio volumio[1122]: Error: connect ETIMEDOUT 31.13.94.49:80 May 06 12:44:36 volumio volumio[1122]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 06 12:44:36 volumio volumio[1122]: errno: -110, May 06 12:44:36 volumio volumio[1122]: code: 'ETIMEDOUT', May 06 12:44:36 volumio volumio[1122]: syscall: 'connect', May 06 12:44:36 volumio volumio[1122]: address: '31.13.94.49', May 06 12:44:36 volumio volumio[1122]: port: 80 May 06 12:44:36 volumio volumio[1122]: } May 06 12:44:36 volumio volumio[1122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 12:44:36 volumio sudo[3101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 12:43 May 06 12:44:36 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"