-- Logs begin at Mon 2026-01-12 15:40:14 CST, end at Mon 2026-01-12 15:53:15 CST. -- Jan 12 15:52:00 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 15:52:00 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:52:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:52:01 volumio volumio[829]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 15:52:01 volumio volumio[829]: info: Retrying Login Due to Network Error Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:06 volumio volumio[829]: info: Jan 12 15:52:06 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand stop took 15 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 6 Jan 12 15:52:06 volumio volumio[829]: info: Jan 12 15:52:06 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:06 volumio volumio[829]: info: Jan 12 15:52:06 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:06 volumio volumio[829]: info: Jan 12 15:52:06 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand status took 7 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 12 15:52:06 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:06 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:52:06 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: No code Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:52:06 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: No code Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:52:06 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: No code Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:52:06 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: No code Jan 12 15:52:06 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:06 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:06 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:06 volumio volumio[829]: info: ------------------------------ 216ms Jan 12 15:52:06 volumio volumio[829]: info: ------------------------------ 214ms Jan 12 15:52:06 volumio volumio[829]: info: ------------------------------ 219ms Jan 12 15:52:06 volumio volumio[829]: info: ------------------------------ 219ms Jan 12 15:52:11 volumio volumio[829]: info: MyVolumio login type: Token Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPlay Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand stop took 1 milliseconds Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand clear took 0 milliseconds Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 1 milliseconds Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 13ms Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 12ms Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand play took 11 milliseconds Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 11ms Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 4ms Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 3ms Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:11 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand status took 6 milliseconds Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 5ms Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 3ms Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:11 volumio volumio[829]: info: Jan 12 15:52:11 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand playlistinfo took 54 milliseconds Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand playlistinfo took 54 milliseconds Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand status took 51 milliseconds Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:52:11 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:52:11 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:11 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:52:11 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:11 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:52:11 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:11 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 92ms Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 144ms Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 144ms Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 143ms Jan 12 15:52:11 volumio volumio[829]: info: sendMpdCommand playlistinfo took 88 milliseconds Jan 12 15:52:11 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:11 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"379 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:52:11 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:11 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:11 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:11 volumio volumio[829]: info: ------------------------------ 209ms Jan 12 15:52:14 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 15:52:14 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:52:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:26 volumio volumio[829]: info: sendMpdCommand stop took 28 milliseconds Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 北国之春.flac Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:26 volumio volumio[829]: info: Jan 12 15:52:26 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:26 volumio volumio[829]: info: sendMpdCommand stop took 1 milliseconds Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:52:26 volumio volumio[829]: info: Jan 12 15:52:26 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:26 volumio volumio[829]: info: Jan 12 15:52:26 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:26 volumio volumio[829]: info: Jan 12 15:52:26 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:26 volumio volumio[829]: info: Jan 12 15:52:26 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:26 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:26 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:26 volumio volumio[829]: info: sendMpdCommand clear took 4 milliseconds Jan 12 15:52:26 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:26 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 北国之春.flac" Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:26 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: 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} Jan 12 15:52:26 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:26 volumio volumio[829]: info: No code Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:26 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: 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} Jan 12 15:52:26 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:26 volumio volumio[829]: info: No code Jan 12 15:52:26 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:26 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:26 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 76ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 76ms Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: error: updateQueue error: null Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand status took 80 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 80ms Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand playlistinfo took 79 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand add "USB/赵鹏 - 北国之春.flac" took 79 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 7ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 7ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 6ms Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: 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} Jan 12 15:52:27 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: No code Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 125ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 129ms Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:27 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 126ms Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand play took 120 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 80ms Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 80ms Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:27 volumio volumio[829]: info: Jan 12 15:52:27 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand status took 47 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand status took 46 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand status took 45 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand status took 39 milliseconds Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand playlistinfo took 23 milliseconds Jan 12 15:52:27 volumio volumio[829]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:27 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:27 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:27 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:27 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:27 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:27 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:27 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:27 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"850 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:27 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:27 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:27 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:27 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 118ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 243ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 243ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 242ms Jan 12 15:52:27 volumio volumio[829]: info: ------------------------------ 242ms Jan 12 15:52:31 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 15:52:31 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:52:31 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioSeek Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::seek Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: TRACKBLOCK {"uri":"mnt/USB/赵鹏 - 北国之春.flac","service":"mpd","name":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E4%B8%AD%E5%9B%BD%E6%9C%80%E5%A5%BD%E5%A3%B0%E9%9F%B3%3A%E4%BA%BA%E5%A3%B0%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E5%A4%A7%E7%A2%9F/extralarge&path=%2FUSB&metadata=false","duration":272,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::seek Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: error: null Jan 12 15:52:35 volumio volumio[829]: info: Jan 12 15:52:35 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:35 volumio volumio[829]: info: Jan 12 15:52:35 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:35 volumio volumio[829]: info: Jan 12 15:52:35 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:35 volumio volumio[829]: info: Jan 12 15:52:35 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:35 volumio volumio[829]: info: sendMpdCommand status took 73 milliseconds Jan 12 15:52:35 volumio volumio[829]: info: sendMpdCommand status took 72 milliseconds Jan 12 15:52:35 volumio volumio[829]: info: sendMpdCommand status took 73 milliseconds Jan 12 15:52:35 volumio volumio[829]: info: sendMpdCommand status took 72 milliseconds Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:35 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:52:35 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58546,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"986 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:35 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:35 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58705,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"958 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:35 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:35 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58705,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"958 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:35 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:35 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58705,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"958 Kbps","isStreaming":false,"title":"北国之春","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:35 volumio volumio[829]: verbose: CURRENT POSITION 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:35 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:35 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:35 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:35 volumio volumio[829]: info: ------------------------------ 212ms Jan 12 15:52:35 volumio volumio[829]: info: ------------------------------ 207ms Jan 12 15:52:35 volumio volumio[829]: info: ------------------------------ 207ms Jan 12 15:52:35 volumio volumio[829]: info: ------------------------------ 206ms Jan 12 15:52:39 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:39 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:39 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:39 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:39 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:39 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:52:39 volumio volumio[829]: info: CorePlayQueue::getTrack 1 Jan 12 15:52:39 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:52:39 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:52:39 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand stop took 28 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/江智民,周虹 - 有没有人告诉你.flac Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 9 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand stop took 8 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 7 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 6 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand clear took 5 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/江智民,周虹 - 有没有人告诉你.flac" Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: 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":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 北国之春.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: No code Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:52:40 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:52:40 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 78ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 77ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 75ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 74ms Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: error: updateQueue error: null Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 69ms Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand add "USB/江智民,周虹 - 有没有人告诉你.flac" took 66 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 4ms Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 59ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 58ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 56ms Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand play took 50 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 42ms Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:40 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: Jan 12 15:52:40 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 46 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 47 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 45 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 45ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 44ms Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:40 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:40 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 72ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 180ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 194ms Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 146 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 147 milliseconds Jan 12 15:52:40 volumio volumio[829]: info: sendMpdCommand playlistinfo took 149 milliseconds Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":569,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:40 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":569,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:40 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":569,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"有没有人告诉你","artist":"江智民/周虹","album":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:40 volumio volumio[829]: verbose: CURRENT POSITION 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:40 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:40 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:40 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 261ms Jan 12 15:52:40 volumio volumio[829]: info: ------------------------------ 260ms Jan 12 15:52:41 volumio volumio[829]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 15:52:41 volumio volumio[829]: info: Retrying Login Due to Network Error Jan 12 15:52:43 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 15:52:43 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:52:43 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:51 volumio volumio[829]: info: MyVolumio login type: Token Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 2 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand stop took 19 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/区瑞强 - 偏偏喜欢你.flac Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 10 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand stop took 7 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand clear took 2 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/区瑞强 - 偏偏喜欢你.flac" Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: 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":"当流行遇上发烧时","uri":"USB/江智民,周虹 - 有没有人告诉你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: No code Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 63ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 57ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 54ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 53ms Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: error: updateQueue error: null Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 57ms Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand add "USB/区瑞强 - 偏偏喜欢你.flac" took 55 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 9ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 8ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 7ms Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:51 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 25ms Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand play took 21 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 18ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 17ms Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: Jan 12 15:52:51 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 75 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 73 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 71 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 69 milliseconds Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 10 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 10 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand status took 9 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"404 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"404 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"490 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"490 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"490 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 123ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 190ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 186ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 184ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 185ms Jan 12 15:52:51 volumio volumio[829]: info: sendMpdCommand playlistinfo took 105 milliseconds Jan 12 15:52:51 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":591,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":591,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":591,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"偏偏喜欢你","artist":"区瑞强","album":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:51 volumio volumio[829]: verbose: CURRENT POSITION 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:51 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:51 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 229ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 229ms Jan 12 15:52:51 volumio volumio[829]: info: ------------------------------ 229ms Jan 12 15:52:55 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 15:52:55 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:52:55 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 3 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand stop took 22 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 白月光.flac Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 12 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand stop took 9 milliseconds Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 7 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand clear took 2 milliseconds Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 白月光.flac" Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: 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":"惠威T200A试音碟","uri":"USB/区瑞强 - 偏偏喜欢你.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: No code Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 76ms Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: error: updateQueue error: null Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 69ms Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 67 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 67 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand add "USB/赵鹏 - 白月光.flac" took 67 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 9ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 6ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 5ms Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:52:57 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 89ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 85ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 84ms Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:52:57 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 31ms Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand play took 23 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 23ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 21ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 20ms Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 10 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: info: Jan 12 15:52:57 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 13 milliseconds Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 74 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 73 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 67 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand status took 64 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 61 milliseconds Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 107ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 266ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 265ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 266ms Jan 12 15:52:57 volumio volumio[829]: info: ------------------------------ 266ms Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 196 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 197 milliseconds Jan 12 15:52:57 volumio volumio[829]: info: sendMpdCommand playlistinfo took 195 milliseconds Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"白月光","artist":"赵鹏","album":"梦——献给大人们的摇篮曲","uri":"USB/赵鹏 - 白月光.flac","trackType":"flac"} Jan 12 15:52:57 volumio volumio[829]: verbose: CURRENT POSITION 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:52:57 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:57 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:52:57 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:52:57 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:52:58 volumio volumio[829]: info: ------------------------------ 382ms Jan 12 15:52:58 volumio volumio[829]: info: ------------------------------ 372ms Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 4 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand stop took 21 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/蔡琴 - 恰似你的温柔.flac Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand stop took 4 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand clear took 6 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/蔡琴 - 恰似你的温柔.flac" Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:00 volumio volumio[829]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 22ms Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 21ms Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 20ms Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 19ms Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: error: updateQueue error: null Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 23ms Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand add "USB/蔡琴 - 恰似你的温柔.flac" took 17 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 7ms Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 6ms Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:00 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 15ms Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 10ms Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand play took 13 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 6ms Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 5ms Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: Jan 12 15:53:00 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 18 milliseconds Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 30 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 29 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand status took 14 milliseconds Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:00 volumio volumio[829]: verbose: CURRENT POSITION 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 41ms Jan 12 15:53:00 volumio volumio[829]: info: sendMpdCommand playlistinfo took 96 milliseconds Jan 12 15:53:00 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":409,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:00 volumio volumio[829]: verbose: CURRENT POSITION 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:00 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":409,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:00 volumio volumio[829]: verbose: CURRENT POSITION 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:00 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":409,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:00 volumio volumio[829]: verbose: CURRENT POSITION 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:00 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:00 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:00 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:00 volumio volumio[829]: info: ------------------------------ 207ms Jan 12 15:53:01 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 15:53:01 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:53:01 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:04 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 12 15:53:04 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:53:04 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioNext Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::next Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::serviceStop Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 5 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::serviceStop Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::stop Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:53:05 volumio volumio[829]: info: Jan 12 15:53:05 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand stop took 18 milliseconds Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 6 Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand status took 6 milliseconds Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:05 volumio volumio[829]: info: Jan 12 15:53:05 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:05 volumio volumio[829]: info: Jan 12 15:53:05 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:05 volumio volumio[829]: info: Jan 12 15:53:05 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:05 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: No code Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: ------------------------------ 71ms Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 12 15:53:05 volumio volumio[829]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:05 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:05 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: No code Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:05 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: No code Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: 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":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Jan 12 15:53:05 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState stateService stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: No code Jan 12 15:53:05 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:05 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:05 volumio volumio[829]: info: ------------------------------ 209ms Jan 12 15:53:05 volumio volumio[829]: info: ------------------------------ 208ms Jan 12 15:53:05 volumio volumio[829]: info: ------------------------------ 209ms Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPlay Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::play index undefined Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand stop took 0 milliseconds Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand clear took 1 milliseconds Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: error: updateQueue error: null Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 4ms Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 3 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 2ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 2ms Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 3ms Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces system playlist update Jan 12 15:53:08 volumio volumio[829]: info: Ignoring MPD Status Update Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand play took 5 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 37ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 36ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 35ms Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: Jan 12 15:53:08 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 105 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 105 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 105 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 99 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 99 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 95 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 145ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 290ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 289ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 285ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 285ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 284ms Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 189ms Jan 12 15:53:08 volumio volumio[829]: info: sendMpdCommand playlistinfo took 188 milliseconds Jan 12 15:53:08 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:08 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:08 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Jan 12 15:53:08 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:08 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:08 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:08 volumio volumio[829]: info: ------------------------------ 265ms Jan 12 15:53:11 volumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 12 15:53:11 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Jan 12 15:53:11 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::volumioPause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::servicePause Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::servicePause Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::pause Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand pause Jan 12 15:53:14 volumio volumio[829]: info: Jan 12 15:53:14 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand pause took 2 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:14 volumio volumio[829]: info: Jan 12 15:53:14 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:14 volumio volumio[829]: info: Jan 12 15:53:14 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:14 volumio volumio[829]: info: Jan 12 15:53:14 volumio volumio[829]: ---------------------------- MPD announces state update: player Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::getState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand status took 1 milliseconds Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseState Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 15:53:14 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:14 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7399,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:14 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7399,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:14 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7399,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:14 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:14 volumio volumio[829]: info: ControllerMpd::pushState Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::servicePushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7399,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jan 12 15:53:14 volumio volumio[829]: verbose: CURRENT POSITION 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::pushState Jan 12 15:53:14 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Jan 12 15:53:14 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Jan 12 15:53:14 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Jan 12 15:53:14 volumio volumio[829]: info: ------------------------------ 73ms Jan 12 15:53:14 volumio volumio[829]: info: ------------------------------ 72ms Jan 12 15:53:14 volumio volumio[829]: info: ------------------------------ 71ms Jan 12 15:53:14 volumio volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 15:53:14 volumio volumio[829]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jan 12 15:53:14 volumio volumio[829]: at Object.readdirSync (fs.js:1048:3) Jan 12 15:53:14 volumio volumio[829]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jan 12 15:53:14 volumio volumio[829]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jan 12 15:53:14 volumio volumio[829]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jan 12 15:53:14 volumio volumio[829]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jan 12 15:53:14 volumio volumio[829]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jan 12 15:53:14 volumio volumio[829]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jan 12 15:53:14 volumio volumio[829]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jan 12 15:53:14 volumio volumio[829]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jan 12 15:53:14 volumio volumio[829]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jan 12 15:53:14 volumio volumio[829]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jan 12 15:53:14 volumio volumio[829]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jan 12 15:53:14 volumio volumio[829]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jan 12 15:53:14 volumio volumio[829]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jan 12 15:53:14 volumio volumio[829]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jan 12 15:53:14 volumio volumio[829]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jan 12 15:53:14 volumio volumio[829]: errno: -20, Jan 12 15:53:14 volumio volumio[829]: syscall: 'scandir', Jan 12 15:53:14 volumio volumio[829]: code: 'ENOTDIR', Jan 12 15:53:14 volumio volumio[829]: path: '/tmp/peppyrunning' Jan 12 15:53:14 volumio volumio[829]: } Jan 12 15:53:14 volumio volumio[829]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 15:53:15 volumio sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 15:52 Jan 12 15:53:15 volumio sudo[3990]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"