-- Logs begin at Wed 2025-09-10 20:54:04 CST, end at Wed 2025-09-10 21:16:53 CST. -- Sep 10 21:15:02 volumio volumio[6457]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.108 UA: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/86.0.4240.198 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::volumioGetState Sep 10 21:15:02 volumio volumio[6457]: info: CorePlayQueue::getTrack 0 Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::volumioGetState Sep 10 21:15:02 volumio volumio[6457]: info: CorePlayQueue::getTrack 0 Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 10 21:15:02 volumio volumio[6457]: info: Received Get System Info Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 21:15:02 volumio volumio[6457]: info: Discovery: Getting this device information Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::volumioGetState Sep 10 21:15:02 volumio volumio[6457]: info: CorePlayQueue::getTrack 0 Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::volumioGetState Sep 10 21:15:02 volumio volumio[6457]: info: CorePlayQueue::getTrack 0 Sep 10 21:15:02 volumio volumio[6457]: info: Listing playlists Sep 10 21:15:02 volumio volumio[6457]: info: CoreCommandRouter::volumioGetQueue Sep 10 21:15:02 volumio volumio[6457]: info: CoreStateMachine::getQueue Sep 10 21:15:02 volumio volumio[6457]: info: CorePlayQueue::getQueue Sep 10 21:15:06 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 21:15:06 volumio volumio[6457]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Sep 10 21:15:06 volumio volumio[6457]: info: CoreCommandRouter::volumioGetState Sep 10 21:15:06 volumio volumio[6457]: info: CorePlayQueue::getTrack 0 Sep 10 21:15:08 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 21:15:08 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 21:15:08 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::volumioGetBrowseSources Sep 10 21:15:11 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 21:15:12 volumio volumio[6457]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 10 21:15:12 volumio volumio[6457]: info: Completed starting MyVolumio Plugin Sep 10 21:15:12 volumio volumio[6457]: [Metrics] CommandRouter: 38s 775.87ms Sep 10 21:15:12 volumio volumio[6457]: info: CoreCommandRouter::volumiosetStartupVolume Sep 10 21:15:12 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 21:15:12 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 21:15:12 volumio volumio[6457]: info: CoreCommandRouter::Close All Modals sent Sep 10 21:15:12 volumio volumio[6457]: info: CoreCommandRouter::Close All Modals sent Sep 10 21:15:13 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 10 21:15:13 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 10 21:15:13 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 10 21:15:19 volumio volumio[6457]: info: BOOT COMPLETED Sep 10 21:15:23 volumio volumio-remote-updater[572]: No test mode Sep 10 21:15:23 volumio volumio-remote-updater[572]: No alpha test mode Sep 10 21:15:23 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 10 21:15:23 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 10 21:15:23 volumio volumio[6457]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 10 21:15:23 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 10 21:15:24 volumio volumio[6457]: info: CoreCommandRouter::volumioPlay Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::play index 16 Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::stop Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::play index undefined Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:24 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:15:24 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/卓依婷-东南西北风.mp3 Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand stop took 3 milliseconds Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:24 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand clear took 5 milliseconds Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/卓依婷-东南西北风.mp3" Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:24 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:24 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:24 volumio volumio[6457]: error: updateQueue error: null Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:24 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:24 volumio volumio[6457]: info: ------------------------------ 13ms Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand add "INTERNAL/卓依婷-东南西北风.mp3" took 10 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: ------------------------------ 9ms Sep 10 21:15:24 volumio volumio[6457]: info: ------------------------------ 8ms Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand play Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:24 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:24 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:24 volumio volumio[6457]: info: ------------------------------ 12ms Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand play took 7 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: ------------------------------ 6ms Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:24 volumio volumio[6457]: info: ------------------------------ 5ms Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand status took 9 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand status took 4 milliseconds Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:24 volumio volumio[6457]: info: Sep 10 21:15:24 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand status took 11 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:24 volumio volumio[6457]: info: sendMpdCommand status took 4 milliseconds Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:24 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:24 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:24 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:24 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:24 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:24 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:24 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:24 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:24 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:24 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:24 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:24 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:24 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:24 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:24 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 55ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 94ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 90ms Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 78 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 76 milliseconds Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 207ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 200ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 199ms Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPlay Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::play index 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::stop Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::stPlaybackTimer Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::updateTrackBlock Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrackBlock Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::serviceStop Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::serviceStop Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::stop Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand stop took 51 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::play index undefined Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/卓依婷-东南西北风.mp3 Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 11 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand stop took 8 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 6 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:25 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand clear took 6 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/卓依婷-东南西北风.mp3" Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: 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":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService stop Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: No code Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 21:15:25 volumio volumio[6457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 75ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 71ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 70ms Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:25 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:25 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:25 volumio volumio[6457]: error: updateQueue error: null Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:25 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 75ms Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand add "INTERNAL/卓依婷-东南西北风.mp3" took 72 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 17ms Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand play Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:25 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:25 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 19ms Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand play took 18 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 17ms Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 13ms Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: Sep 10 21:15:25 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 13 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 13 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 9 milliseconds Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand status took 14 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 39ms Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 10 21:15:25 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东南西北风","artist":"卓依婷","album":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:25 volumio volumio[6457]: verbose: CURRENT POSITION 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:25 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:25 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:25 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 163ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 164ms Sep 10 21:15:25 volumio volumio[6457]: info: ------------------------------ 163ms Sep 10 21:15:30 volumio volumio[6457]: info: CoreCommandRouter::volumioMoveQueue Sep 10 21:15:30 volumio volumio[6457]: info: CoreStateMachine::moveQueueItem 16 16 Sep 10 21:15:30 volumio volumio[6457]: info: CorePlayQueue::moveQueueItem 16 --> 16 Sep 10 21:15:30 volumio volumio[6457]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPlay Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::play index 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::stop Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::stPlaybackTimer Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::updateTrackBlock Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrackBlock Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::serviceStop Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 16 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::serviceStop Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::stop Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand stop took 20 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::play index undefined Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/粤语/张学友-夕阳醉了.flac Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 10 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand stop took 8 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 6 milliseconds Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:37 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand clear took 6 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/粤语/张学友-夕阳醉了.flac" Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: 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":"蜕变4 婷不了的爱","uri":"INTERNAL/卓依婷-东南西北风.mp3","trackType":"mp3"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService stop Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: No code Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 71ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 65ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 63ms Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:37 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:37 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:37 volumio volumio[6457]: error: updateQueue error: null Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:37 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 60ms Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand add "INTERNAL/粤语/张学友-夕阳醉了.flac" took 56 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 7ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 5ms Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand play Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:37 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:37 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 23ms Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand play took 21 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 20ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 14ms Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 21 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 11 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: Sep 10 21:15:37 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 32 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 24 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand status took 14 milliseconds Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:37 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:37 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 120ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 142ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 138ms Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 93 milliseconds Sep 10 21:15:37 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 10 21:15:37 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:37 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:37 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:37 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:37 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:37 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:37 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 233ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 223ms Sep 10 21:15:37 volumio volumio[6457]: info: ------------------------------ 216ms Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::volumioPlay Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::play index 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::stop Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::stPlaybackTimer Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::updateTrackBlock Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrackBlock Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::serviceStop Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::serviceStop Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::stop Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand stop took 29 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::play index undefined Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/粤语/张学友-夕阳醉了.flac Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand status took 17 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand stop took 11 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand status took 2 milliseconds Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:38 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand clear took 6 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/粤语/张学友-夕阳醉了.flac" Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: 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":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:38 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::syncState stateService stop Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:38 volumio volumio[6457]: info: No code Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:38 volumio volumio[6457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 21:15:38 volumio volumio[6457]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 114ms Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 108ms Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 107ms Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:38 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:38 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:38 volumio volumio[6457]: error: updateQueue error: null Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:38 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 98ms Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand add "INTERNAL/粤语/张学友-夕阳醉了.flac" took 98 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 14ms Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand play Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:38 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:15:38 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 26ms Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand play took 17 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 17ms Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: ------------------------------ 13ms Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand status took 11 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand status took 5 milliseconds Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: info: Sep 10 21:15:38 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand status took 13 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 10 21:15:38 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:38 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:38 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:38 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:15:38 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:38 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:38 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:38 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:38 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:39 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:39 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:39 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: ------------------------------ 96ms Sep 10 21:15:39 volumio volumio[6457]: info: ------------------------------ 134ms Sep 10 21:15:39 volumio volumio[6457]: info: ------------------------------ 128ms Sep 10 21:15:39 volumio volumio[6457]: info: sendMpdCommand status took 113 milliseconds Sep 10 21:15:39 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 106 milliseconds Sep 10 21:15:39 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:15:39 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:15:39 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:39 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:39 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:39 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: ------------------------------ 183ms Sep 10 21:15:39 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 72 milliseconds Sep 10 21:15:39 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:15:39 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":309,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"979 Kbps","isStreaming":false,"title":"夕阳醉了","artist":"张学友","album":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:15:39 volumio volumio[6457]: verbose: CURRENT POSITION 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:15:39 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:15:39 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:15:39 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:15:39 volumio volumio[6457]: info: ------------------------------ 222ms Sep 10 21:16:26 volumio volumio[6457]: info: CoreCommandRouter::volumioMoveQueue Sep 10 21:16:26 volumio volumio[6457]: info: CoreStateMachine::moveQueueItem 24 24 Sep 10 21:16:26 volumio volumio[6457]: info: CorePlayQueue::moveQueueItem 24 --> 24 Sep 10 21:16:26 volumio volumio[6457]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:16:31 volumio volumio[6457]: info: CoreCommandRouter::volumioMoveQueue Sep 10 21:16:31 volumio volumio[6457]: info: CoreStateMachine::moveQueueItem 31 31 Sep 10 21:16:31 volumio volumio[6457]: info: CorePlayQueue::moveQueueItem 31 --> 31 Sep 10 21:16:31 volumio volumio[6457]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPlay Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::play index 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::stPlaybackTimer Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::updateTrackBlock Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrackBlock Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::serviceStop Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 23 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::serviceStop Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::stop Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand stop took 29 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::play index undefined Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/赵鹏/赵鹏&柏菲音乐-情人的眼泪.flac Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand status took 6 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand stop took 2 milliseconds Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:16:49 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand clear took 8 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand status took 6 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand status took 4 milliseconds Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/赵鹏/赵鹏&柏菲音乐-情人的眼泪.flac" Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: 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":"金曲精选 1985-1990","uri":"INTERNAL/粤语/张学友-夕阳醉了.flac","trackType":"flac"} Sep 10 21:16:49 volumio volumio[6457]: verbose: CURRENT POSITION 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState stateService stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: No code Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 10 21:16:49 volumio volumio[6457]: verbose: CURRENT POSITION 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState stateService stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: No code Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 10 21:16:49 volumio volumio[6457]: verbose: CURRENT POSITION 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState stateService stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: No code Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 143ms Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 134ms Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 133ms Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:16:49 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:16:49 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:16:49 volumio volumio[6457]: error: updateQueue error: null Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:16:49 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 141ms Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand add "INTERNAL/赵鹏/赵鹏&柏菲音乐-情人的眼泪.flac" took 139 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 11ms Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 9ms Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand play Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:16:49 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces system playlist update Sep 10 21:16:49 volumio volumio[6457]: info: Ignoring MPD Status Update Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 48ms Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand play took 43 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 39ms Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 34ms Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:16:49 volumio volumio[6457]: info: Sep 10 21:16:49 volumio volumio[6457]: ---------------------------- MPD announces state update: player Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::getState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand status Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand status took 14 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand status took 17 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand status took 12 milliseconds Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseState Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 10 21:16:49 volumio volumio[6457]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 10 21:16:49 volumio volumio[6457]: verbose: ControllerMpd::parseTrackInfo Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"628 Kbps","isStreaming":false,"title":"情人的眼泪","artist":"赵鹏&柏菲音乐","album":"2008再低一次","uri":"INTERNAL/赵鹏/赵鹏&柏菲音乐-情人的眼泪.flac","trackType":"flac"} Sep 10 21:16:49 volumio volumio[6457]: verbose: CURRENT POSITION 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus stop Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"628 Kbps","isStreaming":false,"title":"情人的眼泪","artist":"赵鹏&柏菲音乐","album":"2008再低一次","uri":"INTERNAL/赵鹏/赵鹏&柏菲音乐-情人的眼泪.flac","trackType":"flac"} Sep 10 21:16:49 volumio volumio[6457]: verbose: CURRENT POSITION 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:16:49 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: ControllerMpd::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::servicePushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"628 Kbps","isStreaming":false,"title":"情人的眼泪","artist":"赵鹏&柏菲音乐","album":"2008再低一次","uri":"INTERNAL/赵鹏/赵鹏&柏菲音乐-情人的眼泪.flac","trackType":"flac"} Sep 10 21:16:49 volumio volumio[6457]: verbose: CURRENT POSITION 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState stateService play Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::syncState currentStatus play Sep 10 21:16:49 volumio volumio[6457]: info: Received an update from plugin. extracting info from payload Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: CoreStateMachine::pushState Sep 10 21:16:49 volumio volumio[6457]: info: CorePlayQueue::getTrack 41 Sep 10 21:16:49 volumio volumio[6457]: info: CoreCommandRouter::volumioPushState Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 92ms Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 144ms Sep 10 21:16:49 volumio volumio[6457]: info: ------------------------------ 142ms Sep 10 21:16:53 volumio volumio[6457]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 21:16:53 volumio volumio[6457]: Error: connect ETIMEDOUT 108.160.162.109:80 Sep 10 21:16:53 volumio volumio[6457]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 10 21:16:53 volumio volumio[6457]: errno: -110, Sep 10 21:16:53 volumio volumio[6457]: code: 'ETIMEDOUT', Sep 10 21:16:53 volumio volumio[6457]: syscall: 'connect', Sep 10 21:16:53 volumio volumio[6457]: address: '108.160.162.109', Sep 10 21:16:53 volumio volumio[6457]: port: 80 Sep 10 21:16:53 volumio volumio[6457]: } Sep 10 21:16:53 volumio volumio[6457]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 21:16:53 volumio sudo[6968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 21:15 Sep 10 21:16:53 volumio sudo[6968]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"